builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-033 starttime: 1469207122.93 results: success (0) buildid: 20160722094525 builduid: 56b49fd24a6f4927953b4c5bec6ee02a revision: 42532fefe29324b9d002d6bf1cee38c3e55e2cc1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:22.932688) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:22.933151) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:22.933451) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:22.951456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:22.951993) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 10:05:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 57.8M=0s 2016-07-22 10:05:23 (57.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125285 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:23.092784) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:23.093126) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018779 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:23.142861) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:23.143219) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 42532fefe29324b9d002d6bf1cee38c3e55e2cc1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 42532fefe29324b9d002d6bf1cee38c3e55e2cc1 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 10:05:23,224 truncating revision to first 12 chars 2016-07-22 10:05:23,224 Setting DEBUG logging. 2016-07-22 10:05:23,224 attempt 1/10 2016-07-22 10:05:23,224 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/42532fefe293?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 10:05:23,850 unpacking tar archive at: autoland-42532fefe293/testing/mozharness/ program finished with exit code 0 elapsedTime=0.974440 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:24.132828) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:24.133180) ========= script_repo_revision: 42532fefe29324b9d002d6bf1cee38c3e55e2cc1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:24.133597) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:24.133898) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 10:05:24.144166) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-07-22 10:05:24.144606) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:05:24 INFO - MultiFileLogger online at 20160722 10:05:24 in /builds/slave/test 10:05:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 10:05:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:05:24 INFO - {'append_to_log': False, 10:05:24 INFO - 'base_work_dir': '/builds/slave/test', 10:05:24 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 10:05:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:05:24 INFO - 'branch': 'Autoland-Non-PGO', 10:05:24 INFO - 'buildbot_json_path': 'buildprops.json', 10:05:24 INFO - 'config_files': ('talos/linux_config.py',), 10:05:24 INFO - 'default_actions': ('clobber', 10:05:24 INFO - 'read-buildbot-config', 10:05:24 INFO - 'download-and-extract', 10:05:24 INFO - 'populate-webroot', 10:05:24 INFO - 'create-virtualenv', 10:05:24 INFO - 'install', 10:05:24 INFO - 'run-tests'), 10:05:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:05:24 INFO - 'download_minidump_stackwalk': True, 10:05:24 INFO - 'download_symbols': 'ondemand', 10:05:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:05:24 INFO - 'tooltool.py': '/tools/tooltool.py', 10:05:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:05:24 INFO - '/tools/misc-python/virtualenv.py')}, 10:05:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:05:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:05:24 INFO - 'installer_path': 'installer.exe', 10:05:24 INFO - 'log_level': 'info', 10:05:24 INFO - 'log_name': 'talos', 10:05:24 INFO - 'log_to_console': True, 10:05:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:05:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:05:24 INFO - 'opt_config_files': (), 10:05:24 INFO - 'pip_index': False, 10:05:24 INFO - 'sps_profile': False, 10:05:24 INFO - 'sps_profile_interval': 0, 10:05:24 INFO - 'suite': 'dromaeojs', 10:05:24 INFO - 'system_bits': '32', 10:05:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:05:24 INFO - 'title': 'talos-linux64-ix-033', 10:05:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:05:24 INFO - 'use_talos_json': True, 10:05:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:05:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:05:24 INFO - 'work_dir': 'build'} 10:05:24 INFO - [mozharness: 2016-07-22 17:05:24.311583Z] Running clobber step. 10:05:24 INFO - Running pre-action listener: _resource_record_pre_action 10:05:24 INFO - Running main action method: clobber 10:05:24 INFO - rmtree: /builds/slave/test/build 10:05:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:05:25 INFO - Running post-action listener: _resource_record_post_action 10:05:25 INFO - [mozharness: 2016-07-22 17:05:25.290938Z] Finished clobber step (success) 10:05:25 INFO - [mozharness: 2016-07-22 17:05:25.291038Z] Running read-buildbot-config step. 10:05:25 INFO - Running pre-action listener: _resource_record_pre_action 10:05:25 INFO - Running main action method: read_buildbot_config 10:05:25 INFO - Using buildbot properties: 10:05:25 INFO - { 10:05:25 INFO - "project": "", 10:05:25 INFO - "product": "firefox", 10:05:25 INFO - "script_repo_revision": "production", 10:05:25 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 10:05:25 INFO - "repository": "", 10:05:25 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 10:05:25 INFO - "buildid": "20160722094525", 10:05:25 INFO - "pgo_build": "False", 10:05:25 INFO - "basedir": "/builds/slave/test", 10:05:25 INFO - "buildnumber": 212, 10:05:25 INFO - "slavename": "talos-linux64-ix-033", 10:05:25 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:05:25 INFO - "platform": "ubuntu64_hw", 10:05:25 INFO - "scheduler": "tests-autoland-linux64-talos", 10:05:25 INFO - "branch": "autoland", 10:05:25 INFO - "repo_path": "integration/autoland", 10:05:25 INFO - "revision": "42532fefe29324b9d002d6bf1cee38c3e55e2cc1", 10:05:25 INFO - "stage_platform": "linux64", 10:05:25 INFO - "builduid": "56b49fd24a6f4927953b4c5bec6ee02a", 10:05:25 INFO - "slavebuilddir": "test" 10:05:25 INFO - } 10:05:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 10:05:25 INFO - Running post-action listener: _resource_record_post_action 10:05:25 INFO - [mozharness: 2016-07-22 17:05:25.293490Z] Finished read-buildbot-config step (success) 10:05:25 INFO - [mozharness: 2016-07-22 17:05:25.293578Z] Running download-and-extract step. 10:05:25 INFO - Running pre-action listener: _resource_record_pre_action 10:05:25 INFO - Running main action method: download_and_extract 10:05:25 INFO - mkdir: /builds/slave/test/build/tests 10:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:25 INFO - https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:05:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 10:05:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 10:05:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 10:05:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:05:25 INFO - Downloaded 1361 bytes. 10:05:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 10:05:25 INFO - Using the following test package requirements: 10:05:25 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 10:05:25 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:05:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:05:25 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:05:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:05:25 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:05:25 INFO - u'jsshell-linux-x86_64.zip'], 10:05:25 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:05:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:05:25 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 10:05:25 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:05:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:05:25 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:05:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:05:25 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:05:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:05:25 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 10:05:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:05:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:25 INFO - https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:05:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:05:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:05:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:05:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:05:26 INFO - Downloaded 22738199 bytes. 10:05:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:05:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:05:28 INFO - Return code: 0 10:05:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:28 INFO - https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:05:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:05:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:05:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 10:05:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:05:29 INFO - Downloaded 22738199 bytes. 10:05:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:05:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:05:30 INFO - Return code: 0 10:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:30 INFO - https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:05:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 10:05:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 10:05:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 10:05:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:05:31 INFO - Downloaded 14403289 bytes. 10:05:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:05:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 10:05:31 INFO - Return code: 0 10:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:31 INFO - https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:05:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 10:05:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 10:05:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 10:05:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:05:33 INFO - Downloaded 57162553 bytes. 10:05:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 10:05:33 INFO - mkdir: /builds/slave/test/properties 10:05:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:05:33 INFO - Writing to file /builds/slave/test/properties/build_url 10:05:33 INFO - Contents: 10:05:33 INFO - build_url:https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 10:05:34 INFO - Running post-action listener: _resource_record_post_action 10:05:34 INFO - Running post-action listener: set_extra_try_arguments 10:05:34 INFO - [mozharness: 2016-07-22 17:05:34.027572Z] Finished download-and-extract step (success) 10:05:34 INFO - [mozharness: 2016-07-22 17:05:34.027807Z] Running populate-webroot step. 10:05:34 INFO - Running pre-action listener: _resource_record_pre_action 10:05:34 INFO - Running main action method: populate_webroot 10:05:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:05:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:05:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:05:34 INFO - '--tppagecycles', 10:05:34 INFO - '7'], 10:05:34 INFO - 'tests': ['tsvgm']}}, 10:05:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:05:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:05:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:05:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:05:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:34 INFO - 'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:34 INFO - 'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['damp', 'tps']}, 10:05:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:34 INFO - 'tests': ['damp', 'tps']}, 10:05:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['dromaeo_dom']}, 10:05:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:05:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['basic_compositor_video']}, 10:05:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:05:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['a11yr', 10:05:34 INFO - 'ts_paint', 10:05:34 INFO - 'tpaint', 10:05:34 INFO - 'sessionrestore', 10:05:34 INFO - 'sessionrestore_no_auto_restore', 10:05:34 INFO - 'tabpaint']}, 10:05:34 INFO - 'other-e10s': {'tests': ['a11yr', 10:05:34 INFO - 'ts_paint', 10:05:34 INFO - 'tpaint', 10:05:34 INFO - 'sessionrestore', 10:05:34 INFO - 'sessionrestore_no_auto_restore', 10:05:34 INFO - 'tabpaint']}, 10:05:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:05:34 INFO - 'ts_paint', 10:05:34 INFO - 'tpaint', 10:05:34 INFO - 'sessionrestore', 10:05:34 INFO - 'sessionrestore_no_auto_restore', 10:05:34 INFO - 'tabpaint']}, 10:05:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:05:34 INFO - 'ts_paint', 10:05:34 INFO - 'tpaint', 10:05:34 INFO - 'sessionrestore', 10:05:34 INFO - 'sessionrestore_no_auto_restore', 10:05:34 INFO - 'tabpaint']}, 10:05:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['a11yr', 10:05:34 INFO - 'ts_paint', 10:05:34 INFO - 'tpaint', 10:05:34 INFO - 'sessionrestore', 10:05:34 INFO - 'sessionrestore_no_auto_restore', 10:05:34 INFO - 'tabpaint']}, 10:05:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['a11yr', 10:05:34 INFO - 'ts_paint', 10:05:34 INFO - 'tpaint', 10:05:34 INFO - 'sessionrestore', 10:05:34 INFO - 'sessionrestore_no_auto_restore', 10:05:34 INFO - 'tabpaint']}, 10:05:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['tsvgx', 10:05:34 INFO - 'tsvgr_opacity', 10:05:34 INFO - 'tart', 10:05:34 INFO - 'tscrollx', 10:05:34 INFO - 'cart']}, 10:05:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:05:34 INFO - 'tsvgr_opacity', 10:05:34 INFO - 'tart', 10:05:34 INFO - 'tscrollx', 10:05:34 INFO - 'cart']}, 10:05:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:34 INFO - 'talos_options': ['--disable-e10s'], 10:05:34 INFO - 'tests': ['tp5o']}, 10:05:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:34 INFO - 'tests': ['tp5o']}, 10:05:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:34 INFO - 'talos_options': ['--disable-e10s', 10:05:34 INFO - '--xperf_path', 10:05:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:34 INFO - 'tests': ['tp5n']}, 10:05:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:34 INFO - 'talos_options': ['--xperf_path', 10:05:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:34 INFO - 'tests': ['tp5n']}}, 10:05:34 INFO - 'talos.zip': {'path': '', 10:05:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:05:34 INFO - Running post-action listener: _resource_record_post_action 10:05:34 INFO - [mozharness: 2016-07-22 17:05:34.049611Z] Finished populate-webroot step (success) 10:05:34 INFO - [mozharness: 2016-07-22 17:05:34.049700Z] Running create-virtualenv step. 10:05:34 INFO - Running pre-action listener: _resource_record_pre_action 10:05:34 INFO - Running main action method: create_virtualenv 10:05:34 INFO - Creating virtualenv /builds/slave/test/build/venv 10:05:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:05:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:05:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:05:34 INFO - Using real prefix '/usr' 10:05:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:05:34 INFO - Installing distribute.............................................................................................................................................................................................done. 10:05:36 INFO - Installing pip.................done. 10:05:36 INFO - Return code: 0 10:05:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dae180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:05:36 INFO - Using env: {'DISPLAY': ':0', 10:05:36 INFO - 'HOME': '/home/cltbld', 10:05:36 INFO - 'LANG': 'en_US.UTF-8', 10:05:36 INFO - 'LANGUAGE': 'en_US:en', 10:05:36 INFO - 'LOGNAME': 'cltbld', 10:05:36 INFO - 'MAIL': '/var/mail/cltbld', 10:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:36 INFO - 'MOZ_NO_REMOTE': '1', 10:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:36 INFO - 'NO_EM_RESTART': '1', 10:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:36 INFO - 'PWD': '/builds/slave/test', 10:05:36 INFO - 'SHELL': '/bin/bash', 10:05:36 INFO - 'SHLVL': '1', 10:05:36 INFO - 'TERM': 'linux', 10:05:36 INFO - 'TMOUT': '86400', 10:05:36 INFO - 'USER': 'cltbld', 10:05:36 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:36 INFO - '_': '/tools/buildbot/bin/python'} 10:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:05:36 INFO - Cleaning up... 10:05:36 INFO - Return code: 0 10:05:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dae180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:05:36 INFO - Using env: {'DISPLAY': ':0', 10:05:36 INFO - 'HOME': '/home/cltbld', 10:05:36 INFO - 'LANG': 'en_US.UTF-8', 10:05:36 INFO - 'LANGUAGE': 'en_US:en', 10:05:36 INFO - 'LOGNAME': 'cltbld', 10:05:36 INFO - 'MAIL': '/var/mail/cltbld', 10:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:36 INFO - 'MOZ_NO_REMOTE': '1', 10:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:36 INFO - 'NO_EM_RESTART': '1', 10:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:36 INFO - 'PWD': '/builds/slave/test', 10:05:36 INFO - 'SHELL': '/bin/bash', 10:05:36 INFO - 'SHLVL': '1', 10:05:36 INFO - 'TERM': 'linux', 10:05:36 INFO - 'TMOUT': '86400', 10:05:36 INFO - 'USER': 'cltbld', 10:05:36 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:36 INFO - '_': '/tools/buildbot/bin/python'} 10:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:37 INFO - Downloading/unpacking psutil>=3.1.1 10:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:05:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:39 INFO - Installing collected packages: psutil 10:05:39 INFO - Running setup.py install for psutil 10:05:39 INFO - building 'psutil._psutil_linux' extension 10:05:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:05:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:05:39 INFO - building 'psutil._psutil_posix' extension 10:05:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:05:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:05:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:39 INFO - Successfully installed psutil 10:05:39 INFO - Cleaning up... 10:05:39 INFO - Return code: 0 10:05:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dae180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:05:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:05:39 INFO - Using env: {'DISPLAY': ':0', 10:05:39 INFO - 'HOME': '/home/cltbld', 10:05:39 INFO - 'LANG': 'en_US.UTF-8', 10:05:39 INFO - 'LANGUAGE': 'en_US:en', 10:05:39 INFO - 'LOGNAME': 'cltbld', 10:05:39 INFO - 'MAIL': '/var/mail/cltbld', 10:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:39 INFO - 'MOZ_NO_REMOTE': '1', 10:05:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:39 INFO - 'NO_EM_RESTART': '1', 10:05:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:39 INFO - 'PWD': '/builds/slave/test', 10:05:39 INFO - 'SHELL': '/bin/bash', 10:05:39 INFO - 'SHLVL': '1', 10:05:39 INFO - 'TERM': 'linux', 10:05:39 INFO - 'TMOUT': '86400', 10:05:39 INFO - 'USER': 'cltbld', 10:05:39 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:39 INFO - '_': '/tools/buildbot/bin/python'} 10:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:05:41 INFO - Installing collected packages: mozsystemmonitor 10:05:41 INFO - Running setup.py install for mozsystemmonitor 10:05:41 INFO - Successfully installed mozsystemmonitor 10:05:41 INFO - Cleaning up... 10:05:41 INFO - Return code: 0 10:05:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dae180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:05:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:05:41 INFO - Using env: {'DISPLAY': ':0', 10:05:41 INFO - 'HOME': '/home/cltbld', 10:05:41 INFO - 'LANG': 'en_US.UTF-8', 10:05:41 INFO - 'LANGUAGE': 'en_US:en', 10:05:41 INFO - 'LOGNAME': 'cltbld', 10:05:41 INFO - 'MAIL': '/var/mail/cltbld', 10:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:41 INFO - 'MOZ_NO_REMOTE': '1', 10:05:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:41 INFO - 'NO_EM_RESTART': '1', 10:05:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:41 INFO - 'PWD': '/builds/slave/test', 10:05:41 INFO - 'SHELL': '/bin/bash', 10:05:41 INFO - 'SHLVL': '1', 10:05:41 INFO - 'TERM': 'linux', 10:05:41 INFO - 'TMOUT': '86400', 10:05:41 INFO - 'USER': 'cltbld', 10:05:41 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:41 INFO - '_': '/tools/buildbot/bin/python'} 10:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:42 INFO - Downloading/unpacking jsonschema==2.5.1 10:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:05:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:44 INFO - Downloading functools32-3.2.3-2.tar.gz 10:05:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:05:44 INFO - warning: no files found matching '*.txt' 10:05:44 INFO - no previously-included directories found matching 'build' 10:05:44 INFO - no previously-included directories found matching 'dist' 10:05:44 INFO - no previously-included directories found matching '.git*' 10:05:44 INFO - Installing collected packages: jsonschema, functools32 10:05:44 INFO - Running setup.py install for functools32 10:05:44 INFO - warning: no files found matching '*.txt' 10:05:44 INFO - no previously-included directories found matching 'build' 10:05:44 INFO - no previously-included directories found matching 'dist' 10:05:44 INFO - no previously-included directories found matching '.git*' 10:05:44 INFO - Successfully installed jsonschema functools32 10:05:44 INFO - Cleaning up... 10:05:44 INFO - Return code: 0 10:05:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dae180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:05:44 INFO - Using env: {'DISPLAY': ':0', 10:05:44 INFO - 'HOME': '/home/cltbld', 10:05:44 INFO - 'LANG': 'en_US.UTF-8', 10:05:44 INFO - 'LANGUAGE': 'en_US:en', 10:05:44 INFO - 'LOGNAME': 'cltbld', 10:05:44 INFO - 'MAIL': '/var/mail/cltbld', 10:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:44 INFO - 'MOZ_NO_REMOTE': '1', 10:05:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:44 INFO - 'NO_EM_RESTART': '1', 10:05:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:44 INFO - 'PWD': '/builds/slave/test', 10:05:44 INFO - 'SHELL': '/bin/bash', 10:05:44 INFO - 'SHLVL': '1', 10:05:44 INFO - 'TERM': 'linux', 10:05:44 INFO - 'TMOUT': '86400', 10:05:44 INFO - 'USER': 'cltbld', 10:05:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:44 INFO - '_': '/tools/buildbot/bin/python'} 10:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:44 INFO - Downloading/unpacking blobuploader==1.2.4 10:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:46 INFO - Downloading blobuploader-1.2.4.tar.gz 10:05:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:05:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:05:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:47 INFO - Downloading docopt-0.6.1.tar.gz 10:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:05:47 INFO - Installing collected packages: blobuploader, requests, docopt 10:05:47 INFO - Running setup.py install for blobuploader 10:05:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:05:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:05:47 INFO - Running setup.py install for requests 10:05:47 INFO - Running setup.py install for docopt 10:05:47 INFO - Successfully installed blobuploader requests docopt 10:05:47 INFO - Cleaning up... 10:05:48 INFO - Return code: 0 10:05:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dae180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:05:48 INFO - Using env: {'DISPLAY': ':0', 10:05:48 INFO - 'HOME': '/home/cltbld', 10:05:48 INFO - 'LANG': 'en_US.UTF-8', 10:05:48 INFO - 'LANGUAGE': 'en_US:en', 10:05:48 INFO - 'LOGNAME': 'cltbld', 10:05:48 INFO - 'MAIL': '/var/mail/cltbld', 10:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:48 INFO - 'MOZ_NO_REMOTE': '1', 10:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:48 INFO - 'NO_EM_RESTART': '1', 10:05:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:48 INFO - 'PWD': '/builds/slave/test', 10:05:48 INFO - 'SHELL': '/bin/bash', 10:05:48 INFO - 'SHLVL': '1', 10:05:48 INFO - 'TERM': 'linux', 10:05:48 INFO - 'TMOUT': '86400', 10:05:48 INFO - 'USER': 'cltbld', 10:05:48 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:48 INFO - '_': '/tools/buildbot/bin/python'} 10:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:48 INFO - Running setup.py (path:/tmp/pip-7HD_8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:48 INFO - Running setup.py (path:/tmp/pip-TRYnAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:48 INFO - Running setup.py (path:/tmp/pip-NcLj9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:48 INFO - Running setup.py (path:/tmp/pip-qkXYSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:48 INFO - Running setup.py (path:/tmp/pip-L6ni6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:48 INFO - Running setup.py (path:/tmp/pip-w01VWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:48 INFO - Running setup.py (path:/tmp/pip-IuVcmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:48 INFO - Running setup.py (path:/tmp/pip-090G3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:48 INFO - Running setup.py (path:/tmp/pip-EbOTVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:48 INFO - Running setup.py (path:/tmp/pip-h3EL2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:48 INFO - Running setup.py (path:/tmp/pip-z8uTN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:49 INFO - Running setup.py (path:/tmp/pip-pZwuOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:49 INFO - Running setup.py (path:/tmp/pip-_UwIzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:49 INFO - Running setup.py (path:/tmp/pip-HBNBEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:49 INFO - Running setup.py (path:/tmp/pip-OTZvZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:49 INFO - Running setup.py (path:/tmp/pip-SgKmaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:49 INFO - Running setup.py (path:/tmp/pip-8MZXKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:05:49 INFO - Running setup.py install for manifestparser 10:05:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:05:49 INFO - Running setup.py install for mozcrash 10:05:49 INFO - Running setup.py install for mozdebug 10:05:49 INFO - Running setup.py install for mozdevice 10:05:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:05:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:05:49 INFO - Running setup.py install for mozfile 10:05:50 INFO - Running setup.py install for mozhttpd 10:05:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Running setup.py install for mozinfo 10:05:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Running setup.py install for mozInstall 10:05:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Running setup.py install for mozleak 10:05:50 INFO - Running setup.py install for mozlog 10:05:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Running setup.py install for moznetwork 10:05:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Running setup.py install for mozprocess 10:05:50 INFO - Running setup.py install for mozprofile 10:05:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Running setup.py install for mozrunner 10:05:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:05:50 INFO - Running setup.py install for mozscreenshot 10:05:51 INFO - Running setup.py install for moztest 10:05:51 INFO - Running setup.py install for mozversion 10:05:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:05:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:05:51 INFO - Cleaning up... 10:05:51 INFO - Return code: 0 10:05:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dae180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:05:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:05:51 INFO - Using env: {'DISPLAY': ':0', 10:05:51 INFO - 'HOME': '/home/cltbld', 10:05:51 INFO - 'LANG': 'en_US.UTF-8', 10:05:51 INFO - 'LANGUAGE': 'en_US:en', 10:05:51 INFO - 'LOGNAME': 'cltbld', 10:05:51 INFO - 'MAIL': '/var/mail/cltbld', 10:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:51 INFO - 'MOZ_NO_REMOTE': '1', 10:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:51 INFO - 'NO_EM_RESTART': '1', 10:05:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:51 INFO - 'PWD': '/builds/slave/test', 10:05:51 INFO - 'SHELL': '/bin/bash', 10:05:51 INFO - 'SHLVL': '1', 10:05:51 INFO - 'TERM': 'linux', 10:05:51 INFO - 'TMOUT': '86400', 10:05:51 INFO - 'USER': 'cltbld', 10:05:51 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:51 INFO - '_': '/tools/buildbot/bin/python'} 10:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:51 INFO - Running setup.py (path:/tmp/pip-zFjZkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:51 INFO - Running setup.py (path:/tmp/pip-GrlOWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:51 INFO - Running setup.py (path:/tmp/pip-0Cg2yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:51 INFO - Running setup.py (path:/tmp/pip-7SgWKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:51 INFO - Running setup.py (path:/tmp/pip-3LrhTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:51 INFO - Running setup.py (path:/tmp/pip-2djhyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:51 INFO - Running setup.py (path:/tmp/pip-Xn72Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:52 INFO - Running setup.py (path:/tmp/pip-ywrDlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:52 INFO - Running setup.py (path:/tmp/pip-754VHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:52 INFO - Running setup.py (path:/tmp/pip-Hw4p_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:52 INFO - Running setup.py (path:/tmp/pip-d0wzvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:52 INFO - Running setup.py (path:/tmp/pip-Gp0ijY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:52 INFO - Running setup.py (path:/tmp/pip-FxpSFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:52 INFO - Running setup.py (path:/tmp/pip-l2aLfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:52 INFO - Running setup.py (path:/tmp/pip-7SA_kp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:52 INFO - Running setup.py (path:/tmp/pip-esTqTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:52 INFO - Running setup.py (path:/tmp/pip-nKBm8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:05:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:54 INFO - Downloading blessings-1.6.tar.gz 10:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:05:54 INFO - Installing collected packages: blessings 10:05:54 INFO - Running setup.py install for blessings 10:05:54 INFO - Successfully installed blessings 10:05:54 INFO - Cleaning up... 10:05:54 INFO - Return code: 0 10:05:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:05:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:55 INFO - Reading from file tmpfile_stdout 10:05:55 INFO - Current package versions: 10:05:55 INFO - argparse == 1.2.1 10:05:55 INFO - blessings == 1.6 10:05:55 INFO - blobuploader == 1.2.4 10:05:55 INFO - docopt == 0.6.1 10:05:55 INFO - functools32 == 3.2.3-2 10:05:55 INFO - jsonschema == 2.5.1 10:05:55 INFO - manifestparser == 1.1 10:05:55 INFO - mozInstall == 1.12 10:05:55 INFO - mozcrash == 0.17 10:05:55 INFO - mozdebug == 0.1 10:05:55 INFO - mozdevice == 0.48 10:05:55 INFO - mozfile == 1.2 10:05:55 INFO - mozhttpd == 0.7 10:05:55 INFO - mozinfo == 0.9 10:05:55 INFO - mozleak == 0.1 10:05:55 INFO - mozlog == 3.3 10:05:55 INFO - moznetwork == 0.27 10:05:55 INFO - mozprocess == 0.23 10:05:55 INFO - mozprofile == 0.28 10:05:55 INFO - mozrunner == 6.12 10:05:55 INFO - mozscreenshot == 0.1 10:05:55 INFO - mozsystemmonitor == 0.3 10:05:55 INFO - moztest == 0.7 10:05:55 INFO - mozversion == 1.4 10:05:55 INFO - psutil == 3.1.1 10:05:55 INFO - requests == 1.2.3 10:05:55 INFO - wsgiref == 0.1.2 10:05:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dae180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:05:55 INFO - Using env: {'DISPLAY': ':0', 10:05:55 INFO - 'HOME': '/home/cltbld', 10:05:55 INFO - 'LANG': 'en_US.UTF-8', 10:05:55 INFO - 'LANGUAGE': 'en_US:en', 10:05:55 INFO - 'LOGNAME': 'cltbld', 10:05:55 INFO - 'MAIL': '/var/mail/cltbld', 10:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:55 INFO - 'MOZ_NO_REMOTE': '1', 10:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:55 INFO - 'NO_EM_RESTART': '1', 10:05:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:55 INFO - 'PWD': '/builds/slave/test', 10:05:55 INFO - 'SHELL': '/bin/bash', 10:05:55 INFO - 'SHLVL': '1', 10:05:55 INFO - 'TERM': 'linux', 10:05:55 INFO - 'TMOUT': '86400', 10:05:55 INFO - 'USER': 'cltbld', 10:05:55 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:55 INFO - '_': '/tools/buildbot/bin/python'} 10:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:05:55 INFO - Cleaning up... 10:05:55 INFO - Return code: 0 10:05:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1aed030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dae180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:05:55 INFO - Using env: {'DISPLAY': ':0', 10:05:55 INFO - 'HOME': '/home/cltbld', 10:05:55 INFO - 'LANG': 'en_US.UTF-8', 10:05:55 INFO - 'LANGUAGE': 'en_US:en', 10:05:55 INFO - 'LOGNAME': 'cltbld', 10:05:55 INFO - 'MAIL': '/var/mail/cltbld', 10:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:55 INFO - 'MOZ_NO_REMOTE': '1', 10:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:55 INFO - 'NO_EM_RESTART': '1', 10:05:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:55 INFO - 'PWD': '/builds/slave/test', 10:05:55 INFO - 'SHELL': '/bin/bash', 10:05:55 INFO - 'SHLVL': '1', 10:05:55 INFO - 'TERM': 'linux', 10:05:55 INFO - 'TMOUT': '86400', 10:05:55 INFO - 'USER': 'cltbld', 10:05:55 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:55 INFO - '_': '/tools/buildbot/bin/python'} 10:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:05:55 INFO - Cleaning up... 10:05:55 INFO - Return code: 0 10:05:55 INFO - Running post-action listener: _resource_record_post_action 10:05:55 INFO - Running post-action listener: _start_resource_monitoring 10:05:55 INFO - Starting resource monitoring. 10:05:55 INFO - [mozharness: 2016-07-22 17:05:55.594986Z] Finished create-virtualenv step (success) 10:05:55 INFO - [mozharness: 2016-07-22 17:05:55.595420Z] Running install step. 10:05:55 INFO - Running pre-action listener: _resource_record_pre_action 10:05:55 INFO - Running main action method: install 10:05:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:55 INFO - Reading from file tmpfile_stdout 10:05:55 INFO - Detecting whether we're running mozinstall >=1.0... 10:05:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:05:55 INFO - Reading from file tmpfile_stdout 10:05:55 INFO - Output received: 10:05:55 INFO - Usage: mozinstall [options] installer 10:05:55 INFO - Options: 10:05:55 INFO - -h, --help show this help message and exit 10:05:55 INFO - -d DEST, --destination=DEST 10:05:55 INFO - Directory to install application into. [default: 10:05:55 INFO - "/builds/slave/test"] 10:05:55 INFO - --app=APP Application being installed. [default: firefox] 10:05:55 INFO - mkdir: /builds/slave/test/build/application 10:05:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:06:12 INFO - Reading from file tmpfile_stdout 10:06:12 INFO - Output received: 10:06:12 INFO - /builds/slave/test/build/application/firefox/firefox 10:06:12 INFO - Running post-action listener: _resource_record_post_action 10:06:12 INFO - [mozharness: 2016-07-22 17:06:12.269923Z] Finished install step (success) 10:06:12 INFO - [mozharness: 2016-07-22 17:06:12.270209Z] Running run-tests step. 10:06:12 INFO - Running pre-action listener: _resource_record_pre_action 10:06:12 INFO - Running main action method: run_tests 10:06:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:06:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:06:12 INFO - Python 2.7.3 10:06:12 INFO - Return code: 0 10:06:12 INFO - grabbing minidump binary from tooltool 10:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:12 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dac3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dad2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dad740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:06:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:06:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:06:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:06:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:06:12 INFO - Return code: 0 10:06:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:06:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:06:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:06:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:06:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:06:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:06:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:06:12 INFO - Using env: {'DISPLAY': ':0', 10:06:12 INFO - 'HOME': '/home/cltbld', 10:06:12 INFO - 'LANG': 'en_US.UTF-8', 10:06:12 INFO - 'LANGUAGE': 'en_US:en', 10:06:12 INFO - 'LOGNAME': 'cltbld', 10:06:12 INFO - 'MAIL': '/var/mail/cltbld', 10:06:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:06:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:06:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:12 INFO - 'MOZ_NO_REMOTE': '1', 10:06:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:06:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:06:12 INFO - 'NO_EM_RESTART': '1', 10:06:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:06:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:12 INFO - 'PWD': '/builds/slave/test', 10:06:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:06:12 INFO - 'SHELL': '/bin/bash', 10:06:12 INFO - 'SHLVL': '1', 10:06:12 INFO - 'TERM': 'linux', 10:06:12 INFO - 'TMOUT': '86400', 10:06:12 INFO - 'USER': 'cltbld', 10:06:12 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571', 10:06:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:12 INFO - '_': '/tools/buildbot/bin/python'} 10:06:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:06:12 INFO - mozversion application_buildid: 20160722094525 10:06:12 INFO - mozversion application_changeset: 42532fefe29324b9d002d6bf1cee38c3e55e2cc1 10:06:12 INFO - mozversion application_display_name: Nightly 10:06:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:06:12 INFO - mozversion application_name: Firefox 10:06:12 INFO - mozversion application_remotingname: firefox 10:06:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:06:12 INFO - mozversion application_vendor: Mozilla 10:06:12 INFO - mozversion application_version: 50.0a1 10:06:12 INFO - mozversion platform_buildid: 20160722094525 10:06:12 INFO - mozversion platform_changeset: 42532fefe29324b9d002d6bf1cee38c3e55e2cc1 10:06:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:06:12 INFO - mozversion platform_version: 50.0a1 10:06:12 INFO - using testdate: 1469207172 10:06:12 INFO - actual date: 1469207172 10:06:12 INFO - starting webserver on 'localhost:52282' 10:06:12 INFO - SUITE-START | Running 2 tests 10:06:12 INFO - TEST-START | dromaeo_css 10:06:12 INFO - operating with platform_type : linux_ 10:06:12 INFO - Initialising browser for dromaeo_css test... 10:06:12 INFO - TEST-INFO | started process 3801 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAoBrIA/profile http://localhost:52282/getInfo.html) 10:06:16 INFO - PROCESS | 3801 | JavaScript error: , line 0: uncaught exception: undefined 10:06:17 INFO - PROCESS | 3801 | __metrics Screen width/height:1600/1200 10:06:17 INFO - PROCESS | 3801 | colorDepth:24 10:06:17 INFO - PROCESS | 3801 | Browser inner width/height: 1024/697 10:06:17 INFO - PROCESS | 3801 | __metrics 10:06:18 INFO - PROCESS | 3801 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:06:18 INFO - PROCESS | 3801 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:06:19 INFO - TEST-INFO | 3801: exit 0 10:06:19 INFO - Browser initialized. 10:06:19 INFO - Running cycle 1/1 for dromaeo_css test... 10:06:19 INFO - TEST-INFO | started process 3884 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAoBrIA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:31 INFO - PROCESS | 3884 | RSS: Main: 158371840 10:06:31 INFO - PROCESS | 3884 | 10:07:23 INFO - PROCESS | 3884 | 1469207243260 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:09:35 INFO - PROCESS | 3884 | Cycle 1(1): loaded http://localhost:52282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52282/tests/dromaeo/cssquery-ext.html) 10:09:36 INFO - PROCESS | 3884 | RSS: Main: 237604864 10:09:36 INFO - PROCESS | 3884 | 10:12:39 INFO - PROCESS | 3884 | Cycle 1(1): loaded http://localhost:52282/tests/dromaeo/cssquery-ext.html (next: http://localhost:52282/tests/dromaeo/cssquery-jquery.html) 10:12:40 INFO - PROCESS | 3884 | RSS: Main: 247066624 10:12:40 INFO - PROCESS | 3884 | 10:15:42 INFO - PROCESS | 3884 | Cycle 1(1): loaded http://localhost:52282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52282/tests/dromaeo/cssquery-mootools.html) 10:15:42 INFO - PROCESS | 3884 | RSS: Main: 217026560 10:15:42 INFO - PROCESS | 3884 | 10:18:45 INFO - PROCESS | 3884 | Cycle 1(1): loaded http://localhost:52282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52282/tests/dromaeo/cssquery-prototype.html) 10:18:45 INFO - PROCESS | 3884 | RSS: Main: 208375808 10:18:45 INFO - PROCESS | 3884 | 10:21:48 INFO - PROCESS | 3884 | Cycle 1(1): loaded http://localhost:52282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52282/tests/dromaeo/cssquery-yui.html) 10:21:49 INFO - PROCESS | 3884 | RSS: Main: 190689280 10:21:49 INFO - PROCESS | 3884 | 10:24:52 INFO - PROCESS | 3884 | Cycle 1(1): loaded http://localhost:52282/tests/dromaeo/cssquery-yui.html (next: http://localhost:52282/tests/dromaeo/cssquery-dojo.html) 10:24:52 INFO - PROCESS | 3884 | RSS: Main: 286699520 10:24:52 INFO - PROCESS | 3884 | 10:24:52 INFO - PROCESS | 3884 | __start_tp_report 10:24:52 INFO - PROCESS | 3884 | _x_x_mozilla_page_load 10:24:52 INFO - PROCESS | 3884 | _x_x_mozilla_page_load_details 10:24:52 INFO - PROCESS | 3884 | |i|pagename|runs| 10:24:52 INFO - PROCESS | 3884 | |0;dojo.html;2599.155274535776;2624.147152175541;2652.164568161029;2666.7599916007543;2670.8263962842416;4637.420322459692;4716.6462515311405;4735.029318989607;4795.952040479594;4825.1314763342625;4674.672772905898;4702.9529704702945;4734.4081989751285;4737.431508219001;4743.312219728137;4268.253055715253;4285.614294713475;4311.1593239318345;4370.453693288339;4471.4857791353925;4375.956240437603;4380.277254253045;4463.598276155145;4468.910621787579;4470.396496472967;4243.342281946303;4281.828726850922;4321.87631215883;4363.956360436387;4412.205802955466;4249.936250956238;4279.315309550489;4304.849330273457;4310.849120280806;4319.956800431987;4310.3534469829465;4318;4319.69762116652;4368.322909948963;4371.169477799208;4703.1064097821645;4773.570378665937;4775.641826863;4784.162771514971;4800.495947925452;4977.253411988195;5008.424031236418;5060.139776238011;5159.896802063975;5172.120739474299;5136.229565565158;5139.58883289336;5143.871403214912;5146.150885103953;5146.536811686929;4768.809247630091;4778.354922085474;4799.400074990626;4802.927956080662;4805.975970120127;5005.349304590452;5087.109755792795;5126.87182820425;5133.537981581676;5251.52736253739;4886.462489126193;4912.680675756064;4946.455889852114;4959.628027897922;4961.231009193581;5022.347094877642;5025.748712564357;5062.240663900371;5067.746612669352;5069.163588008011;4925.704457732548;4972.776225069881;4989.501049894981;5019.071471777733;5033.723145227048;5019.322391477177;5023.09584274834;5024.42219144803;5031.245313202976;5040.697558146523;4336.1978034063795;4339.522652508284;4340.414044104069;4371.125774845043;4384.254676704904;3716.0524066362905;3755.9061023474087;3764.5294338207723;3781.3949768036982;3795.9240815183543;4285.400043993844;4305.461817272841;4318.417013703172;4340.739555626673;4342.717723347972;5035.546800787946;5041.0422019816115;5042.319286896295;5042.823501177441;5104.69371837691;4223.007593215591;4300.849470268526;4303.806328715215;4308.9353659695125;4350.108227813291;4568.520305367955;4613.2388155953895;4619.953800461953;4631.490536041033;4678.461976872703;4755.619550435957;4771.165046116985;4771.475137734916;4777.617790576745;4819.807207711652;4901.90196196067;5117.769700363418;5143.434222235476;5146.485351464824;5147.974260128825;4708.693934894221;4741.028089241629;4744.928826067543;4747.335373047707;4805.975970120127;5036.924446133385;5040.269160971712;5044.041632089891;5053.2420136979745;5100.413452452867;4984.476629953948;5109.744512774272;5121.948780512147;5122.564582010571;5122.641415100908;4919.163742163769;4959.231319145538;4984.42679091909;4995.050940321327;5020.974895125501;4997.400311962442;5008.84973450797;5009.874753131201;5011.223260394645;5060.645754797276;4751.572358487752;4880.511948805091;4899.559039686445;4946.579540739078;4953.331300274417;4923.753812309442;4971.751412429437;4993.226049962262;5003.77483013258;5029.245613157909;4860.222364421676;4879.707217566957;4885.9022819544125;4891.070696567641;4940.135476291563 10:24:52 INFO - PROCESS | 3884 | |1;ext.html;559.4182050667306;578.4620303118098;588.9499392551633;595.3897255313304;605.9394060593941;27085.864570677142;29966.400671986576;30078.84960575194;30079.84960075202;30137.24656883579;29750.851245743743;39600.40599391004;41307.58692413075;41514.16971660565;45244.54755452445;7620.390368770485;7741.264579864904;7743.7676869694;7744.419168562352;7749.535027898316;7681.961590192069;7787.922120778776;7793.259640334188;7795.220477952216;7795.493292935941;7418.851622967537;10236.334638248527;10530.526126324296;10546.947265263663;10551.841722374133;9974.202063834875;9986.300958932878;10036.096751292382;10048.849267260997;10074.395536267848;7342.412606991428;9826.1156495915;9890.554925028391;9897.109260166619;9901.158401535879;27821;30450.84774576135;37827.4325885112;38253.426198606765;38292;2875.578971458139;2940;2965.24386281499;3071.155432256125;3274.5944218013237;27394.452110957867;28921.276968075756;29062.564061539095;29307;29684.257893552618;3331.933361332784;3459.636738142484;3478.530398396235;3523.7533372663925;3597.2985267872778;272852;274554;274801;275090.999999998;275154;55012;55129;55429.722851386294;55606;55741.442585574434;46903.296450553265;47388.76305618519;48241;50428.495715043115;58104.41895581075;15131.773023404658;15303.540893773205;15333.616659583377;15353.923230383776;15364.923175384052;11681.88318116825;11790.056795456343;11800.173987820941;11806.232594881305;11810.468528916219;15634.374625014872;17788.288468461375;18080.547986300448;18177.909110454362;18361.816381836277;6591.439727623206;6881.690323935435;6914.9308506914285;6924.169099708067;7159.031266401677;801.8717005279244;946.1531928923575;953.880764904387;973.026973026973;1007.7329507680471;27550.124688279302;28080;28249.435011299658;28725.712742872303;28832.855835720686;926.4348747264163;953.1231267234161;968.4963818814177;1008.7931973945324;1054.9683509494728;14669.48656797007;14812.481563145238;14957.850421495647;15326.770098448533;15371.231438428034;396448;398836;399825;400471;401743;44401;48666.513334866904;49253.75373123183;49674.254886176735;49838.252426213636;1197.3294954825326;1206.6621346023128;1209.3771707570431;1220.2871497507801;1245.7944439167618;27773.194742341682;28890.133296001153;29346.413071738873;29906.252343691584;29951.251218719706;41951;42359;42374.152516950104;42411.787941060094;42429.787851060544;609510;629826;630159;632681;632788;35981;36266.8186659065;36503.45244821383;36544.45183322306;37125.443118352705;37137.44293835541;37851.242975139794;41562;42029;42980;35736.28527429489;36203.09492262715;36269.45595816012;36292.637073628925;36739.44890826693;36125.81937090298;36410;36967.44548831716;37098.44352334663;37175.25649487049;36028.27943441169;36212.63787362093;36582.26835463222;36725;37287.44068839024;31768.841155794074;32738;33255.667443325256;33708.49437258394;33710;25429.872850635627;25545.361365966;25748.87125564435;25777.613335800354;26001.60997585 10:24:52 INFO - PROCESS | 3884 | |2;jquery.html;340.94446558190344;358.37821379905876;362.9256002519483;366.6260414377334;376.8837974771658;12986.155899866513;16550.17249137543;16738.330466781328;17552.824471755277;18479;16889.408870689527;16924.069176195306;16932.661346773057;17049.403270885523;17588.29646814126;4946.876328091799;5117.974410127944;5313.335833020872;5371.033214021474;5445.673259604417;3510.7366947478913;5379.865503362427;5411.107167317407;5422.213779002042;5583.553315734752;5843.824685259449;5894.528437724972;5902.498287645534;5902.527797776189;5913.438223368795;5019.146745053313;5199.714015729133;5276.261323414725;5901.232839730829;5915.763369465195;5185.325907632021;5208.166693329087;5224.059669259532;5227.634065615409;5228.92156617651;13166.73666526664;15025.173615451145;21331.146754129815;21402.250921217837;21489;1738.2873022060894;1824.1700026487915;1920.596674698315;1927.441042097804;2119.671450925109;9214.401063930824;9694.36986595869;9696.903030969741;9734.41593504385;13154;1725.7325114607256;1774.1838754173095;1787.30295184878;1810.2125575374623;1825.7900341460636;71398;73371.26628733697;74334.25665743327;76706.61646691787;77565;9761.658341957997;10113.64602238918;10158.206493214426;10212.285140040125;10440;14012.719745605234;14031.719365612631;14036.508722194674;14045.85954140466;14053.22707251111;7042.6830792614455;7177.820554486179;7237.8552428951125;7255.56466612005;7319.268073192638;6571.408573228432;6630.933690663128;6776.966115169491;6818.28408017161;6824.317568243136;4242.766647834399;4317.56824317572;4337.917799456216;4443.800028998703;4458.308962110878;1773;1839.3286450445758;1848.5286252005653;1865.766779152606;1881.595456976729;737.2553720742089;846.6401779243797;851.3657325292696;853.9444936079135;886.0652012127139;16920.238589263266;16991;17212.70904682129;17383.391581294592;18154.90922545379;831.3806214370333;894.4230971023784;899.1008991008991;909.0591238068567;911.5214512381053;9591.760205994766;10126.746831329128;10152.746181345525;10161.4919254037;10205.38767673942;432185;436126;443316;444994;447173;85488.14511854803;86611;87738;87913.56043219742;88035;916.8395530782086;948.9905100948902;981.6858605246252;981.7496538382667;984.6553706202772;15725.606859828138;15951.920240399188;16193.75709364384;16299.429519966745;16699.415520456725;38953.22093558056;39453.210935781695;39573;39840.60159398369;39876.2024759509;54079.72960135174;54447.72776136094;54958.72520637371;55425.168622469886;55982.44017559772;7470.813229669302;7579.431542634435;7598.696052157852;7636.8472630548185;8128.63421146038;6529.771457998947;8525.658973640984;10771.19216058783;11399.316041037233;11406.48670809799;9893;9908.801823963337;9986.300958932805;10173.338732982333;10327.638532651321;9385.202257808163;10085.848712269175;10116.645917392856;10207.540660670436;10297.3821570706;9247.583858726239;9405.20055795265;9840.655577055055;10399.376037437778;10715.142788577052;7505.362044226302;7527;7615.771526854203;7645.388368930583;7797.376209903307;9425.481598512148;9875.555599997873;10170.288079834341;10224.53989570486;10282.948585257025 10:24:52 INFO - PROCESS | 3884 | |3;mootools.html;1260.350919276572;1379.1656048090902;1430.864067913548;1506.47273454291;1684.8230935751737;6710.597364158154;7363.226861179573;7703.614819259043;7706.6146692665425;7707;7370.4472164587605;7509.812254693636;7510.624468776553;7691.5000524965835;7695.192004839495;2117.1213946212342;2165.2421652421604;2167.6856855755987;2167.945801354963;2168.544605632819;2116.608427440928;2158.719366482355;2173.8369622278315;2177.4991751897073;2179.814715749163;2137.487003119256;2150.6343921533376;2159.92003998001;2160.643493823525;2172.4134483689395;2109.050927082811;2117.3436234767273;2121.554473560554;2122.1299267300333;2124.3733098735756;2081.157131361801;2129.829613630906;2131.0836340373635;2132.104516103225;2137.262644387684;7520.059992500937;7742.380609551223;7752.961235193844;7762.379009679212;7789.532628042336;4508.188526065307;4691.718496890164;4695.812167513295;4695.88260293492;4697.506761790031;2751.9587206191923;2861.2989817494845;2889.8699558519916;2910.3888183481495;2911.679715231323;910.5128756115447;911.5032307392421;926.703454894434;933.2207606648457;951.0346997797242;10587.417692026935;10731.35611863283;10742.677719668422;10771;10777.730556735987;3948.506436695413;4163.084980908755;4250.086231460251;4253.553376895444;4265.402843601898;9248.028956959422;9264.027277135938;9272;9292.628294868266;9293.210077143518;4626.583607475276;4629.953700463019;4636.281376386665;4645.3032045193495;4649.604783593365;3722.181120153562;4118.258713431551;4190.5180904195795;4199.286121359376;4293.484781826201;3664.6152154023443;3704.7959413190283;3805.847766089381;3855.325318069383;3865.2076324354002;1191.4281145050425;1194.5759255464359;1199.3463562358536;1219.5731493977037;1231.975360492803;826.4751882554501;849.6856163219649;854.0775961961067;871.7689812199773;886.8536691445968;5131.461196574306;5293.523582877559;5358.490943360374;5394.973025134849;5404.351477822608;403.57221345373995;406.638092098033;409.01223545138157;410.20625090450073;422.52043930139513;3655.6709896108946;3728.4593733908973;3754.1928485375774;3760.1351689111657;3779.9433008504893;9549.713508594754;9834.655787047419;9952.054554817281;9984;10110.39337639744;9908.455034973002;9923.950380248198;9927.900720992842;9928.702138935843;9931.205503559842;7379.446541509408;7584.2794934481135;7592.658330375147;7599.202083781123;7602.201768814195;5667.2349232854685;5729.427057294237;5756.510696590837;5815.94184058154;5865.296164460292;9229.307801915018;9321.580528876082;9327.906720932704;9333.300002499704;9335.953320233355;9107.681231157143;9166.816663666823;9330.720078397659;9331.066893310615;9420.764480888032;5730.312362516358;6592.67036648156;6731.158605174353;6792.422644075309;7058.188308344606;7373.5575865448245;7486.513376630502;7647.197044310267;7669.6548655309525;7673.961630191813;6495.805125846232;6511.7395304189195;6530.8040758777315;6659.633720145439;6734.696938637675;6465.515086368635;6497.805065848032;6506.121673574007;6555.541112122106;6563.310852360624;6131.202943617302;6256.874862502816;6261.812145635638;6265.27949285838;6267.435930766071;4574.336721175478;4743.881402964954;4793;4835.032993401263;4840.757962101951;4668.393108895822;4831.275308703582;4856.68431551948;4870.147724148189;4930.531599498042 10:24:52 INFO - PROCESS | 3884 | |4;prototype.html;276.2114164061604;277.8013720190064;280.5371137622923;284.3460041903621;284.6768917278888;4849.709017458955;4890.804367825292;4900.921797204618;4907.509249075095;4929.412058669201;4552.226121559334;4693.460252071013;4707.317438971347;4709.199436095863;4862.927056094161;3684.392075307571;3688.5758137814228;3694.2426802505556;3742.6070262622443;3802.6007269236748;3789.5073640426845;3820.847166113352;3821.388577827548;3827.947314488524;3829.215010922768;3666.6883314918264;3802.1065049713297;3807.086299288165;3819.9427008594753;3820.426935959614;3538.7876727396183;3660.3777357848962;3761.097336639187;3792.829322680472;3811.104390468238;3332.483465062919;3345.9498107528407;3350.681685239923;3361.159710072482;3365.2428203654226;4725.267583524559;4732.219183834663;4734.976325118352;4738.24188129901;4765.928511072337;4325.286327755916;4328.696991210617;4334.176506463762;4362.498312694049;4364.388985542027;4826.61387089032;5045.520675535855;5069.949300507022;5087.516685914832;5089.618278629117;3628.0952499937744;3663.5528966058523;3674;3680.4663323818163;3684.6315368463206;5296.761645725913;5323.946760532383;5326.573874090063;5340.25236466895;5361.705106219196;3564.0733409313248;3740.3454395481226;3785.3943369060817;3800.3349413853016;3801.6958643308467;3842.423636454554;3849.191669749384;3855.248226595843;3865.468118382464;3865.942010869839;4363.607275345171;4378.956210437855;4383.364412160283;4384.386185933972;4405.502129276061;4925.729084900293;5044.672096313728;5057.7724002419245;5192.221166825007;5193.740312984336;1308.6074177746827;1317.8155058291848;1326.3036905624624;1326.608650448101;1327.482281910056;833.5457175839182;844.344536432915;859.3426029087752;865.0268447996004;871.5250188647202;4021.778802165909;4029.5567487576345;4035.8991025224605;4047.170330082326;4063.7968101595393;4627.95870929045;4665.300204969281;4668.85993420198;4672.392588963481;4682.601978831769;3990.68074554035;3993.580674029185;4026.053877338823;4039.6162364575907;4063.89840253996;2499.8250122491613;2588.65726517502;2782.930426739307;2796.2310364649884;2798.768541841583;4839.951600484021;4850.054239423349;4875.463698993108;4884.462709101996;4885.438174609894;5339.519443250126;5348.304720386325;5352.839414817562;5362.597805164551;5394.002109609734;4648.58162765346;4857.344258525054;4935.062338155739;4938.38270216223;4953.578945789648;5226.843194704165;5241.659292145999;5253.789848406021;5257.6056795739705;5314.97342513285;4919.754012299442;5184.781576329635;5194.948050519446;5216.2436446714255;5230.843074707765;4870;4889.975550122226;4890.364252647133;4894.265860120868;4903.5586797188425;3585.300866331145;3608.4587311903424;3616.65641764032;3627.709783217389;3631.945520817137;5143.408508021475;5233.188855727369;5244.868878277921;5290.28581141541;5293.470652934676;5046.243063540498;5110.284560161655;5115.590752739847;5133.845984620468;5133.8716532087;5101.897962040664;5104.183330667075;5118.129917914038;5119.28330033803;5122.334096567422;4901.558859702502;4907.460179380194;5021.698698077981;5030.522100400456;5041.344625198701;4906.779194936165;5060.3927528696795;5068.113080211022;5128.820491282787;5177.4822517749435;4876.244182151772;4932.926006109839;4953.950460495349;4955.975220123876;4956.628252881121 10:24:52 INFO - PROCESS | 3884 | |5;yui.html;564.5709260961669;590.988180236395;632.2634131237108;633.7781776378267;646.789793317172;12539.31033793141;13180.340982950851;13182.670433239175;13665.863341366608;13707.246101464416;11434.942825285883;12011.27932324059;12058.09564282678;12179.695507612292;12246.0815438842;755.6939439526986;768.5964868444083;769.5959621198859;769.9461037727361;770.445279398832;768.8616049111158;771.8301973565807;772.3357912195536;772.7256823827545;773.0993392697501;805.2913436176157;806.5160903457908;806.9919300807005;807.2371608829658;807.2694211738386;798.0264077825044;799.5003123048095;799.5442597719332;799.7001124578284;800.315730050804;585.6398315036265;586.1208187718422;586.5542187937156;586.712510869675;586.8620874094585;11335.943320283346;11523.366214858179;11537.94231028848;11542;11575.42122893852;10167.694969150863;10295.279330446872;10439.164866810637;10791.352518848895;10879.456027198608;9622.566984485715;9693.854592181124;9792.314537982344;9872.60509579616;9882.555285012191;3151.4169878572307;3177.984110079458;3179.1257404213793;3209.5988001499813;3218.4045951499047;51267.23099153516;52456.21315680306;59338;59892.401075989554;60003.099953500736;10240.180785537139;10429.843552346721;10589.682309530726;10689.144868410509;10734.57061717538;18499;18541;18654.34709785151;18886.05569721536;18900;6666.366695163952;6806.7957961261245;6827.419669328064;6912.412444942136;7196;3683.7605555638797;3770.7737535747942;3771.26460340231;3787.394016957274;3845.8846234612447;2791.260316016257;2844.1751891951517;2845;2860.985695071511;2879.827210367385;507.2163507381105;508.659198337115;518.6732358614124;522.5741021067894;525.9211118332204;500.669558091665;517.5161224255334;539.5359990408245;585.3239508367842;598.1565991951328;7640.923590764021;7832.84334313322;8010.879836802453;8024.157463466368;8252.711155109662;473.3704173449373;521.72609380075;523.2595876834237;530.9469053094737;541.8834950485665;3806.0865392305645;3947.3092208863336;3948.4274780156716;4037.6769858411258;4095.365218391154;93650;93675.53162234281;93758.53120734352;94149.5292523533;96524;79639;80174.599127004;80242.19757802464;82739;83232.58383708043;44052.55947440484;67750.32249677539;69429.3057069433;70367.99999999898;73027;8264.21489958453;8883.600237989176;9086.681966131153;9135.817283654422;9296.535173241446;43508;43662;44606.76966151615;46091.53908461006;46345;54846.11392758728;65743;65944.67027665023;65969.34030659824;66679;11439.714007149554;11878;11914.16600837933;12100.152989290666;12191.939040304742;12544.937275313565;12881.742365152832;13362.198268103944;13417.396217170055;13527.188368697909;11503.482343294405;11583.884161158618;11585.131115166228;11767.646970590897;12110.45502952387;11996.100292477924;12439.315837629018;12443.502259909503;12475.064370172455;12519.374031298217;10639.344506665591;11540.769184616092;12021;12027.939860300992;12029.879701202875;9463.716088517356;9516.476593787409;9696.205422527159;9880.209583233467;9890.703278901645;9070.410423322463;10125.291229613857;10203.132733717717;10219.642312519027;10267.589296428358 10:24:52 INFO - PROCESS | 3884 | __end_tp_report 10:24:52 INFO - PROCESS | 3884 | __start_cc_report 10:24:52 INFO - PROCESS | 3884 | _x_x_mozilla_cycle_collect,1131 10:24:52 INFO - PROCESS | 3884 | __end_cc_report 10:24:52 INFO - PROCESS | 3884 | __startTimestamp1469208292384__endTimestamp 10:24:52 INFO - PROCESS | 3884 | 10:24:52 INFO - PROCESS | 3884 | ------- Summary: start ------- 10:24:52 INFO - PROCESS | 3884 | Number of tests: 6 10:24:52 INFO - PROCESS | 3884 | 10:24:52 INFO - PROCESS | 3884 | [#0] dojo.html Cycles:165 Average:4686.64 Median:4805.98 stddev:494.84 (10.3%) stddev-sans-first:468.48 10:24:52 INFO - PROCESS | 3884 | Values: 2599.2 2624.1 2652.2 2666.8 2670.8 4637.4 4716.6 4735.0 4796.0 4825.1 4674.7 4703.0 4734.4 4737.4 4743.3 4268.3 4285.6 4311.2 4370.5 4471.5 4376.0 4380.3 4463.6 4468.9 4470.4 4243.3 4281.8 4321.9 4364.0 4412.2 4249.9 4279.3 4304.8 4310.8 4320.0 4310.4 4318.0 4319.7 4368.3 4371.2 4703.1 4773.6 4775.6 4784.2 4800.5 4977.3 5008.4 5060.1 5159.9 5172.1 5136.2 5139.6 5143.9 5146.2 5146.5 4768.8 4778.4 4799.4 4802.9 4806.0 5005.3 5087.1 5126.9 5133.5 5251.5 4886.5 4912.7 4946.5 4959.6 4961.2 5022.3 5025.7 5062.2 5067.7 5069.2 4925.7 4972.8 4989.5 5019.1 5033.7 5019.3 5023.1 5024.4 5031.2 5040.7 4336.2 4339.5 4340.4 4371.1 4384.3 3716.1 3755.9 3764.5 3781.4 3795.9 4285.4 4305.5 4318.4 4340.7 4342.7 5035.5 5041.0 5042.3 5042.8 5104.7 4223.0 4300.8 4303.8 4308.9 4350.1 4568.5 4613.2 4620.0 4631.5 4678.5 4755.6 4771.2 4771.5 4777.6 4819.8 4901.9 5117.8 5143.4 5146.5 5148.0 4708.7 4741.0 4744.9 4747.3 4806.0 5036.9 5040.3 5044.0 5053.2 5100.4 4984.5 5109.7 5121.9 5122.6 5122.6 4919.2 4959.2 4984.4 4995.1 5021.0 4997.4 5008.8 5009.9 5011.2 5060.6 4751.6 4880.5 4899.6 4946.6 4953.3 4923.8 4971.8 4993.2 5003.8 5029.2 4860.2 4879.7 4885.9 4891.1 4940.1 10:24:52 INFO - PROCESS | 3884 | 10:24:52 INFO - PROCESS | 3884 | [#1] ext.html Cycles:180 Average:57047.50 Median:36281.05 stddev:122469.98 (337.6%) stddev-sans-first:122740.10 10:24:52 INFO - PROCESS | 3884 | Values: 559.4 578.5 588.9 595.4 605.9 27085.9 29966.4 30078.8 30079.8 30137.2 29750.9 39600.4 41307.6 41514.2 45244.5 7620.4 7741.3 7743.8 7744.4 7749.5 7682.0 7787.9 7793.3 7795.2 7795.5 7418.9 10236.3 10530.5 10546.9 10551.8 9974.2 9986.3 10036.1 10048.8 10074.4 7342.4 9826.1 9890.6 9897.1 9901.2 27821.0 30450.8 37827.4 38253.4 38292.0 2875.6 2940.0 2965.2 3071.2 3274.6 27394.5 28921.3 29062.6 29307.0 29684.3 3331.9 3459.6 3478.5 3523.8 3597.3 272852.0 274554.0 274801.0 275091.0 275154.0 55012.0 55129.0 55429.7 55606.0 55741.4 46903.3 47388.8 48241.0 50428.5 58104.4 15131.8 15303.5 15333.6 15353.9 15364.9 11681.9 11790.1 11800.2 11806.2 11810.5 15634.4 17788.3 18080.5 18177.9 18361.8 6591.4 6881.7 6914.9 6924.2 7159.0 801.9 946.2 953.9 973.0 1007.7 27550.1 28080.0 28249.4 28725.7 28832.9 926.4 953.1 968.5 1008.8 1055.0 14669.5 14812.5 14957.9 15326.8 15371.2 396448.0 398836.0 399825.0 400471.0 401743.0 44401.0 48666.5 49253.8 49674.3 49838.3 1197.3 1206.7 1209.4 1220.3 1245.8 27773.2 28890.1 29346.4 29906.3 29951.3 41951.0 42359.0 42374.2 42411.8 42429.8 609510.0 629826.0 630159.0 632681.0 632788.0 35981.0 36266.8 36503.5 36544.5 37125.4 37137.4 37851.2 41562.0 42029.0 42980.0 35736.3 36203.1 36269.5 36292.6 36739.4 36125.8 36410.0 36967.4 37098.4 37175.3 36028.3 36212.6 36582.3 36725.0 37287.4 31768.8 32738.0 33255.7 33708.5 33710.0 25429.9 25545.4 25748.9 25777.6 26001.6 10:24:52 INFO - PROCESS | 3884 | 10:24:52 INFO - PROCESS | 3884 | [#2] jquery.html Cycles:180 Average:26275.49 Median:5068.56 stddev:72868.96 (1437.7%) stddev-sans-first:73047.35 10:24:52 INFO - PROCESS | 3884 | Values: 340.9 358.4 362.9 366.6 376.9 12986.2 16550.2 16738.3 17552.8 18479.0 16889.4 16924.1 16932.7 17049.4 17588.3 4946.9 5118.0 5313.3 5371.0 5445.7 3510.7 5379.9 5411.1 5422.2 5583.6 5843.8 5894.5 5902.5 5902.5 5913.4 5019.1 5199.7 5276.3 5901.2 5915.8 5185.3 5208.2 5224.1 5227.6 5228.9 13166.7 15025.2 21331.1 21402.3 21489.0 1738.3 1824.2 1920.6 1927.4 2119.7 9214.4 9694.4 9696.9 9734.4 13154.0 1725.7 1774.2 1787.3 1810.2 1825.8 71398.0 73371.3 74334.3 76706.6 77565.0 9761.7 10113.6 10158.2 10212.3 10440.0 14012.7 14031.7 14036.5 14045.9 14053.2 7042.7 7177.8 7237.9 7255.6 7319.3 6571.4 6630.9 6777.0 6818.3 6824.3 4242.8 4317.6 4337.9 4443.8 4458.3 1773.0 1839.3 1848.5 1865.8 1881.6 737.3 846.6 851.4 853.9 886.1 16920.2 16991.0 17212.7 17383.4 18154.9 831.4 894.4 899.1 909.1 911.5 9591.8 10126.7 10152.7 10161.5 10205.4 432185.0 436126.0 443316.0 444994.0 447173.0 85488.1 86611.0 87738.0 87913.6 88035.0 916.8 949.0 981.7 981.7 984.7 15725.6 15951.9 16193.8 16299.4 16699.4 38953.2 39453.2 39573.0 39840.6 39876.2 54079.7 54447.7 54958.7 55425.2 55982.4 7470.8 7579.4 7598.7 7636.8 8128.6 6529.8 8525.7 10771.2 11399.3 11406.5 9893.0 9908.8 9986.3 10173.3 10327.6 9385.2 10085.8 10116.6 10207.5 10297.4 9247.6 9405.2 9840.7 10399.4 10715.1 7505.4 7527.0 7615.8 7645.4 7797.4 9425.5 9875.6 10170.3 10224.5 10282.9 10:24:52 INFO - PROCESS | 3884 | 10:24:52 INFO - PROCESS | 3884 | [#3] mootools.html Cycles:180 Average:5176.02 Median:5031.00 stddev:2942.54 (58.5%) stddev-sans-first:2936.08 10:24:52 INFO - PROCESS | 3884 | Values: 1260.4 1379.2 1430.9 1506.5 1684.8 6710.6 7363.2 7703.6 7706.6 7707.0 7370.4 7509.8 7510.6 7691.5 7695.2 2117.1 2165.2 2167.7 2167.9 2168.5 2116.6 2158.7 2173.8 2177.5 2179.8 2137.5 2150.6 2159.9 2160.6 2172.4 2109.1 2117.3 2121.6 2122.1 2124.4 2081.2 2129.8 2131.1 2132.1 2137.3 7520.1 7742.4 7753.0 7762.4 7789.5 4508.2 4691.7 4695.8 4695.9 4697.5 2752.0 2861.3 2889.9 2910.4 2911.7 910.5 911.5 926.7 933.2 951.0 10587.4 10731.4 10742.7 10771.0 10777.7 3948.5 4163.1 4250.1 4253.6 4265.4 9248.0 9264.0 9272.0 9292.6 9293.2 4626.6 4630.0 4636.3 4645.3 4649.6 3722.2 4118.3 4190.5 4199.3 4293.5 3664.6 3704.8 3805.8 3855.3 3865.2 1191.4 1194.6 1199.3 1219.6 1232.0 826.5 849.7 854.1 871.8 886.9 5131.5 5293.5 5358.5 5395.0 5404.4 403.6 406.6 409.0 410.2 422.5 3655.7 3728.5 3754.2 3760.1 3779.9 9549.7 9834.7 9952.1 9984.0 10110.4 9908.5 9924.0 9927.9 9928.7 9931.2 7379.4 7584.3 7592.7 7599.2 7602.2 5667.2 5729.4 5756.5 5815.9 5865.3 9229.3 9321.6 9327.9 9333.3 9336.0 9107.7 9166.8 9330.7 9331.1 9420.8 5730.3 6592.7 6731.2 6792.4 7058.2 7373.6 7486.5 7647.2 7669.7 7674.0 6495.8 6511.7 6530.8 6659.6 6734.7 6465.5 6497.8 6506.1 6555.5 6563.3 6131.2 6256.9 6261.8 6265.3 6267.4 4574.3 4743.9 4793.0 4835.0 4840.8 4668.4 4831.3 4856.7 4870.1 4930.5 10:24:52 INFO - PROCESS | 3884 | 10:24:52 INFO - PROCESS | 3884 | [#4] prototype.html Cycles:180 Average:4179.93 Median:4728.74 stddev:1218.42 (25.8%) stddev-sans-first:1186.08 10:24:52 INFO - PROCESS | 3884 | Values: 276.2 277.8 280.5 284.3 284.7 4849.7 4890.8 4900.9 4907.5 4929.4 4552.2 4693.5 4707.3 4709.2 4862.9 3684.4 3688.6 3694.2 3742.6 3802.6 3789.5 3820.8 3821.4 3827.9 3829.2 3666.7 3802.1 3807.1 3819.9 3820.4 3538.8 3660.4 3761.1 3792.8 3811.1 3332.5 3345.9 3350.7 3361.2 3365.2 4725.3 4732.2 4735.0 4738.2 4765.9 4325.3 4328.7 4334.2 4362.5 4364.4 4826.6 5045.5 5069.9 5087.5 5089.6 3628.1 3663.6 3674.0 3680.5 3684.6 5296.8 5323.9 5326.6 5340.3 5361.7 3564.1 3740.3 3785.4 3800.3 3801.7 3842.4 3849.2 3855.2 3865.5 3865.9 4363.6 4379.0 4383.4 4384.4 4405.5 4925.7 5044.7 5057.8 5192.2 5193.7 1308.6 1317.8 1326.3 1326.6 1327.5 833.5 844.3 859.3 865.0 871.5 4021.8 4029.6 4035.9 4047.2 4063.8 4628.0 4665.3 4668.9 4672.4 4682.6 3990.7 3993.6 4026.1 4039.6 4063.9 2499.8 2588.7 2782.9 2796.2 2798.8 4840.0 4850.1 4875.5 4884.5 4885.4 5339.5 5348.3 5352.8 5362.6 5394.0 4648.6 4857.3 4935.1 4938.4 4953.6 5226.8 5241.7 5253.8 5257.6 5315.0 4919.8 5184.8 5194.9 5216.2 5230.8 4870.0 4890.0 4890.4 4894.3 4903.6 3585.3 3608.5 3616.7 3627.7 3631.9 5143.4 5233.2 5244.9 5290.3 5293.5 5046.2 5110.3 5115.6 5133.8 5133.9 5101.9 5104.2 5118.1 5119.3 5122.3 4901.6 4907.5 5021.7 5030.5 5041.3 4906.8 5060.4 5068.1 5128.8 5177.5 4876.2 4932.9 4954.0 4956.0 4956.6 10:24:52 INFO - PROCESS | 3884 | 10:24:52 INFO - PROCESS | 3884 | [#5] yui.html Cycles:180 Average:17109.37 Median:518.09 stddev:24168.04 (4664.8%) stddev-sans-first:24203.91 10:24:52 INFO - PROCESS | 3884 | Values: 564.6 591.0 632.3 633.8 646.8 12539.3 13180.3 13182.7 13665.9 13707.2 11434.9 12011.3 12058.1 12179.7 12246.1 755.7 768.6 769.6 769.9 770.4 768.9 771.8 772.3 772.7 773.1 805.3 806.5 807.0 807.2 807.3 798.0 799.5 799.5 799.7 800.3 585.6 586.1 586.6 586.7 586.9 11335.9 11523.4 11537.9 11542.0 11575.4 10167.7 10295.3 10439.2 10791.4 10879.5 9622.6 9693.9 9792.3 9872.6 9882.6 3151.4 3178.0 3179.1 3209.6 3218.4 51267.2 52456.2 59338.0 59892.4 60003.1 10240.2 10429.8 10589.7 10689.1 10734.6 18499.0 18541.0 18654.3 18886.1 18900.0 6666.4 6806.8 6827.4 6912.4 7196.0 3683.8 3770.8 3771.3 3787.4 3845.9 2791.3 2844.2 2845.0 2861.0 2879.8 507.2 508.7 518.7 522.6 525.9 500.7 517.5 539.5 585.3 598.2 7640.9 7832.8 8010.9 8024.2 8252.7 473.4 521.7 523.3 530.9 541.9 3806.1 3947.3 3948.4 4037.7 4095.4 93650.0 93675.5 93758.5 94149.5 96524.0 79639.0 80174.6 80242.2 82739.0 83232.6 44052.6 67750.3 69429.3 70368.0 73027.0 8264.2 8883.6 9086.7 9135.8 9296.5 43508.0 43662.0 44606.8 46091.5 46345.0 54846.1 65743.0 65944.7 65969.3 66679.0 11439.7 11878.0 11914.2 12100.2 12191.9 12544.9 12881.7 13362.2 13417.4 13527.2 11503.5 11583.9 11585.1 11767.6 12110.5 11996.1 12439.3 12443.5 12475.1 12519.4 10639.3 11540.8 12021.0 12027.9 12029.9 9463.7 9516.5 9696.2 9880.2 9890.7 9070.4 10125.3 10203.1 10219.6 10267.6 10:24:52 INFO - PROCESS | 3884 | -------- Summary: end -------- 10:24:52 INFO - PROCESS | 3884 | 10:24:53 INFO - TEST-INFO | 3884: exit 0 10:24:53 INFO - TEST-OK | dromaeo_css | took 1120698ms 10:24:53 INFO - TEST-START | kraken 10:24:53 INFO - operating with platform_type : linux_ 10:24:53 INFO - Initialising browser for kraken test... 10:24:53 INFO - TEST-INFO | started process 4073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFxPiD1/profile http://localhost:52282/getInfo.html) 10:24:57 INFO - PROCESS | 4073 | JavaScript error: , line 0: uncaught exception: undefined 10:24:58 INFO - PROCESS | 4073 | __metrics Screen width/height:1600/1200 10:24:58 INFO - PROCESS | 4073 | colorDepth:24 10:24:58 INFO - PROCESS | 4073 | Browser inner width/height: 1024/697 10:24:58 INFO - PROCESS | 4073 | __metrics 10:24:59 INFO - PROCESS | 4073 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:24:59 INFO - PROCESS | 4073 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:25:00 INFO - TEST-INFO | 4073: exit 0 10:25:00 INFO - Browser initialized. 10:25:00 INFO - Running cycle 1/1 for kraken test... 10:25:00 INFO - TEST-INFO | started process 4152 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFxPiD1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:25:12 INFO - PROCESS | 4152 | RSS: Main: 158879744 10:25:12 INFO - PROCESS | 4152 | 10:25:15 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52282/tests/kraken/driver.html?testName=audio-beat-detection) 10:25:15 INFO - PROCESS | 4152 | RSS: Main: 214831104 10:25:15 INFO - PROCESS | 4152 | 10:25:18 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52282/tests/kraken/driver.html?testName=audio-dft) 10:25:19 INFO - PROCESS | 4152 | RSS: Main: 194097152 10:25:19 INFO - PROCESS | 4152 | 10:25:22 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52282/tests/kraken/driver.html?testName=audio-fft) 10:25:23 INFO - PROCESS | 4152 | RSS: Main: 273252352 10:25:23 INFO - PROCESS | 4152 | 10:25:25 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52282/tests/kraken/driver.html?testName=audio-oscillator) 10:25:25 INFO - PROCESS | 4152 | RSS: Main: 182493184 10:25:25 INFO - PROCESS | 4152 | 10:25:28 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:25:28 INFO - PROCESS | 4152 | RSS: Main: 186273792 10:25:28 INFO - PROCESS | 4152 | 10:25:31 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52282/tests/kraken/driver.html?testName=imaging-darkroom) 10:25:32 INFO - PROCESS | 4152 | RSS: Main: 222679040 10:25:32 INFO - PROCESS | 4152 | 10:25:36 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52282/tests/kraken/driver.html?testName=imaging-desaturate) 10:25:36 INFO - PROCESS | 4152 | RSS: Main: 222429184 10:25:36 INFO - PROCESS | 4152 | 10:25:39 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52282/tests/kraken/driver.html?testName=json-parse-financial) 10:25:40 INFO - PROCESS | 4152 | RSS: Main: 222195712 10:25:40 INFO - PROCESS | 4152 | 10:25:41 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:25:42 INFO - PROCESS | 4152 | RSS: Main: 186150912 10:25:42 INFO - PROCESS | 4152 | 10:25:44 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52282/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:25:44 INFO - PROCESS | 4152 | RSS: Main: 192524288 10:25:44 INFO - PROCESS | 4152 | 10:25:47 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:25:47 INFO - PROCESS | 4152 | RSS: Main: 196960256 10:25:47 INFO - PROCESS | 4152 | 10:25:50 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:25:51 INFO - PROCESS | 4152 | RSS: Main: 190808064 10:25:51 INFO - PROCESS | 4152 | 10:25:54 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:25:54 INFO - PROCESS | 4152 | RSS: Main: 194572288 10:25:54 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | Cycle 1(1): loaded http://localhost:52282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52282/tests/kraken/driver.html?testName=ai-astar) 10:25:56 INFO - PROCESS | 4152 | RSS: Main: 245071872 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | __start_tp_report 10:25:56 INFO - PROCESS | 4152 | _x_x_mozilla_page_load 10:25:56 INFO - PROCESS | 4152 | _x_x_mozilla_page_load_details 10:25:56 INFO - PROCESS | 4152 | |i|pagename|runs| 10:25:56 INFO - PROCESS | 4152 | |0;ai-astar;106;113;114;115;114;113;116;113;114;113 10:25:56 INFO - PROCESS | 4152 | |1;audio-beat-detection;152;124;123;191;124;127;155;123;164;124 10:25:56 INFO - PROCESS | 4152 | |2;audio-dft;161;162;160;161;160;162;160;161;160;163 10:25:56 INFO - PROCESS | 4152 | |3;audio-fft;75;76;76;126;76;75;75;76;104;75 10:25:56 INFO - PROCESS | 4152 | |4;audio-oscillator;85;85;117;85;85;85;122;84;85;126 10:25:56 INFO - PROCESS | 4152 | |5;imaging-gaussian-blur;117;116;117;117;116;117;116;117;117;117 10:25:56 INFO - PROCESS | 4152 | |6;imaging-darkroom;147;147;147;146;148;146;147;146;148;148 10:25:56 INFO - PROCESS | 4152 | |7;imaging-desaturate;95;95;95;95;94;95;94;95;95;94 10:25:56 INFO - PROCESS | 4152 | |8;json-parse-financial;61;60;60;60;60;60;61;61;61;60 10:25:56 INFO - PROCESS | 4152 | |9;json-stringify-tinderbox;65;65;65;64;64;65;64;66;66;65 10:25:56 INFO - PROCESS | 4152 | |10;stanford-crypto-aes;80;80;79;79;79;80;89;79;79;83 10:25:56 INFO - PROCESS | 4152 | |11;stanford-crypto-ccm;131;137;130;131;126;131;127;129;176;132 10:25:56 INFO - PROCESS | 4152 | |12;stanford-crypto-pbkdf2;166;164;165;164;169;165;165;168;162;162 10:25:56 INFO - PROCESS | 4152 | |13;stanford-crypto-sha256-iterative;68;67;67;67;67;66;66;67;65;65 10:25:56 INFO - PROCESS | 4152 | __end_tp_report 10:25:56 INFO - PROCESS | 4152 | __start_cc_report 10:25:56 INFO - PROCESS | 4152 | _x_x_mozilla_cycle_collect,1987 10:25:56 INFO - PROCESS | 4152 | __end_cc_report 10:25:56 INFO - PROCESS | 4152 | __startTimestamp1469208356655__endTimestamp 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | ------- Summary: start ------- 10:25:56 INFO - PROCESS | 4152 | Number of tests: 14 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:2.69 (2.4%) stddev-sans-first:1.05 10:25:56 INFO - PROCESS | 4152 | Values: 106.0 113.0 114.0 115.0 114.0 113.0 116.0 113.0 114.0 113.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:139.50 stddev:23.70 (17.0%) stddev-sans-first:24.78 10:25:56 INFO - PROCESS | 4152 | Values: 152.0 124.0 123.0 191.0 124.0 127.0 155.0 123.0 164.0 124.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.05 (0.7%) stddev-sans-first:1.12 10:25:56 INFO - PROCESS | 4152 | Values: 161.0 162.0 160.0 161.0 160.0 162.0 160.0 161.0 160.0 163.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#3] audio-fft Cycles:10 Average:83.40 Median:75.50 stddev:17.45 (23.1%) stddev-sans-first:18.24 10:25:56 INFO - PROCESS | 4152 | Values: 75.0 76.0 76.0 126.0 76.0 75.0 75.0 76.0 104.0 75.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:85.00 stddev:17.91 (21.1%) stddev-sans-first:18.56 10:25:56 INFO - PROCESS | 4152 | Values: 85.0 85.0 117.0 85.0 85.0 85.0 122.0 84.0 85.0 126.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 10:25:56 INFO - PROCESS | 4152 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 10:25:56 INFO - PROCESS | 4152 | Values: 147.0 147.0 147.0 146.0 148.0 146.0 147.0 146.0 148.0 148.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:25:56 INFO - PROCESS | 4152 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 94.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 10:25:56 INFO - PROCESS | 4152 | Values: 61.0 60.0 60.0 60.0 60.0 60.0 61.0 61.0 61.0 60.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 10:25:56 INFO - PROCESS | 4152 | Values: 65.0 65.0 65.0 64.0 64.0 65.0 64.0 66.0 66.0 65.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:80.00 stddev:3.16 (4.0%) stddev-sans-first:3.35 10:25:56 INFO - PROCESS | 4152 | Values: 80.0 80.0 79.0 79.0 79.0 80.0 89.0 79.0 79.0 83.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:131.00 stddev:14.71 (11.2%) stddev-sans-first:15.53 10:25:56 INFO - PROCESS | 4152 | Values: 131.0 137.0 130.0 131.0 126.0 131.0 127.0 129.0 176.0 132.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.00 stddev:2.26 (1.4%) stddev-sans-first:2.37 10:25:56 INFO - PROCESS | 4152 | Values: 166.0 164.0 165.0 164.0 169.0 165.0 165.0 168.0 162.0 162.0 10:25:56 INFO - PROCESS | 4152 | 10:25:56 INFO - PROCESS | 4152 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.97 (1.5%) stddev-sans-first:0.87 10:25:56 INFO - PROCESS | 4152 | Values: 68.0 67.0 67.0 67.0 67.0 66.0 66.0 67.0 65.0 65.0 10:25:56 INFO - PROCESS | 4152 | -------- Summary: end -------- 10:25:56 INFO - PROCESS | 4152 | 10:25:58 INFO - TEST-INFO | 4152: exit 0 10:25:58 INFO - TEST-OK | kraken | took 65062ms 10:25:58 INFO - SUITE-END | took 1185s 10:25:58 INFO - Completed test suite (00:19:45) 10:25:58 INFO - javascript benchmark 10:25:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2599.155274535776, 2624.147152175541, 2652.164568161029, 2666.7599916007543, 2670.8263962842416, 4637.420322459692, 4716.6462515311405, 4735.029318989607, 4795.952040479594, 4825.1314763342625, 4674.672772905898, 4702.9529704702945, 4734.4081989751285, 4737.431508219001, 4743.312219728137, 4268.253055715253, 4285.614294713475, 4311.1593239318345, 4370.453693288339, 4471.4857791353925, 4375.956240437603, 4380.277254253045, 4463.598276155145, 4468.910621787579, 4470.396496472967, 4243.342281946303, 4281.828726850922, 4321.87631215883, 4363.956360436387, 4412.205802955466, 4249.936250956238, 4279.315309550489, 4304.849330273457, 4310.849120280806, 4319.956800431987, 4310.3534469829465, 4318.0, 4319.69762116652, 4368.322909948963, 4371.169477799208, 4703.1064097821645, 4773.570378665937, 4775.641826863, 4784.162771514971, 4800.495947925452, 4977.253411988195, 5008.424031236418, 5060.139776238011, 5159.896802063975, 5172.120739474299, 5136.229565565158, 5139.58883289336, 5143.871403214912, 5146.150885103953, 5146.536811686929, 4768.809247630091, 4778.354922085474, 4799.400074990626, 4802.927956080662, 4805.975970120127, 5005.349304590452, 5087.109755792795, 5126.87182820425, 5133.537981581676, 5251.52736253739, 4886.462489126193, 4912.680675756064, 4946.455889852114, 4959.628027897922, 4961.231009193581, 5022.347094877642, 5025.748712564357, 5062.240663900371, 5067.746612669352, 5069.163588008011, 4925.704457732548, 4972.776225069881, 4989.501049894981, 5019.071471777733, 5033.723145227048, 5019.322391477177, 5023.09584274834, 5024.42219144803, 5031.245313202976, 5040.697558146523, 4336.1978034063795, 4339.522652508284, 4340.414044104069, 4371.125774845043, 4384.254676704904, 3716.0524066362905, 3755.9061023474087, 3764.5294338207723, 3781.3949768036982, 3795.9240815183543, 4285.400043993844, 4305.461817272841, 4318.417013703172, 4340.739555626673, 4342.717723347972, 5035.546800787946, 5041.0422019816115, 5042.319286896295, 5042.823501177441, 5104.69371837691, 4223.007593215591, 4300.849470268526, 4303.806328715215, 4308.9353659695125, 4350.108227813291, 4568.520305367955, 4613.2388155953895, 4619.953800461953, 4631.490536041033, 4678.461976872703, 4755.619550435957, 4771.165046116985, 4771.475137734916, 4777.617790576745, 4819.807207711652, 4901.90196196067, 5117.769700363418, 5143.434222235476, 5146.485351464824, 5147.974260128825, 4708.693934894221, 4741.028089241629, 4744.928826067543, 4747.335373047707, 4805.975970120127, 5036.924446133385, 5040.269160971712, 5044.041632089891, 5053.2420136979745, 5100.413452452867, 4984.476629953948, 5109.744512774272, 5121.948780512147, 5122.564582010571, 5122.641415100908, 4919.163742163769, 4959.231319145538, 4984.42679091909, 4995.050940321327, 5020.974895125501, 4997.400311962442, 5008.84973450797, 5009.874753131201, 5011.223260394645, 5060.645754797276, 4751.572358487752, 4880.511948805091, 4899.559039686445, 4946.579540739078, 4953.331300274417, 4923.753812309442, 4971.751412429437, 4993.226049962262, 5003.77483013258, 5029.245613157909, 4860.222364421676, 4879.707217566957, 4885.9022819544125, 4891.070696567641, 4940.135476291563], "value": 4654.778991401605, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.4182050667306, 578.4620303118098, 588.9499392551633, 595.3897255313304, 605.9394060593941, 27085.864570677142, 29966.400671986576, 30078.84960575194, 30079.84960075202, 30137.24656883579, 29750.851245743743, 39600.40599391004, 41307.58692413075, 41514.16971660565, 45244.54755452445, 7620.390368770485, 7741.264579864904, 7743.7676869694, 7744.419168562352, 7749.535027898316, 7681.961590192069, 7787.922120778776, 7793.259640334188, 7795.220477952216, 7795.493292935941, 7418.851622967537, 10236.334638248527, 10530.526126324296, 10546.947265263663, 10551.841722374133, 9974.202063834875, 9986.300958932878, 10036.096751292382, 10048.849267260997, 10074.395536267848, 7342.412606991428, 9826.1156495915, 9890.554925028391, 9897.109260166619, 9901.158401535879, 27821.0, 30450.84774576135, 37827.4325885112, 38253.426198606765, 38292.0, 2875.578971458139, 2940.0, 2965.24386281499, 3071.155432256125, 3274.5944218013237, 27394.452110957867, 28921.276968075756, 29062.564061539095, 29307.0, 29684.257893552618, 3331.933361332784, 3459.636738142484, 3478.530398396235, 3523.7533372663925, 3597.2985267872778, 272852.0, 274554.0, 274801.0, 275090.999999998, 275154.0, 55012.0, 55129.0, 55429.722851386294, 55606.0, 55741.442585574434, 46903.296450553265, 47388.76305618519, 48241.0, 50428.495715043115, 58104.41895581075, 15131.773023404658, 15303.540893773205, 15333.616659583377, 15353.923230383776, 15364.923175384052, 11681.88318116825, 11790.056795456343, 11800.173987820941, 11806.232594881305, 11810.468528916219, 15634.374625014872, 17788.288468461375, 18080.547986300448, 18177.909110454362, 18361.816381836277, 6591.439727623206, 6881.690323935435, 6914.9308506914285, 6924.169099708067, 7159.031266401677, 801.8717005279244, 946.1531928923575, 953.880764904387, 973.026973026973, 1007.7329507680471, 27550.124688279302, 28080.0, 28249.435011299658, 28725.712742872303, 28832.855835720686, 926.4348747264163, 953.1231267234161, 968.4963818814177, 1008.7931973945324, 1054.9683509494728, 14669.48656797007, 14812.481563145238, 14957.850421495647, 15326.770098448533, 15371.231438428034, 396448.0, 398836.0, 399825.0, 400471.0, 401743.0, 44401.0, 48666.513334866904, 49253.75373123183, 49674.254886176735, 49838.252426213636, 1197.3294954825326, 1206.6621346023128, 1209.3771707570431, 1220.2871497507801, 1245.7944439167618, 27773.194742341682, 28890.133296001153, 29346.413071738873, 29906.252343691584, 29951.251218719706, 41951.0, 42359.0, 42374.152516950104, 42411.787941060094, 42429.787851060544, 609510.0, 629826.0, 630159.0, 632681.0, 632788.0, 35981.0, 36266.8186659065, 36503.45244821383, 36544.45183322306, 37125.443118352705, 37137.44293835541, 37851.242975139794, 41562.0, 42029.0, 42980.0, 35736.28527429489, 36203.09492262715, 36269.45595816012, 36292.637073628925, 36739.44890826693, 36125.81937090298, 36410.0, 36967.44548831716, 37098.44352334663, 37175.25649487049, 36028.27943441169, 36212.63787362093, 36582.26835463222, 36725.0, 37287.44068839024, 31768.841155794074, 32738.0, 33255.667443325256, 33708.49437258394, 33710.0, 25429.872850635627, 25545.361365966, 25748.87125564435, 25777.613335800354, 26001.60997585], "value": 18313.290625301408, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.94446558190344, 358.37821379905876, 362.9256002519483, 366.6260414377334, 376.8837974771658, 12986.155899866513, 16550.17249137543, 16738.330466781328, 17552.824471755277, 18479.0, 16889.408870689527, 16924.069176195306, 16932.661346773057, 17049.403270885523, 17588.29646814126, 4946.876328091799, 5117.974410127944, 5313.335833020872, 5371.033214021474, 5445.673259604417, 3510.7366947478913, 5379.865503362427, 5411.107167317407, 5422.213779002042, 5583.553315734752, 5843.824685259449, 5894.528437724972, 5902.498287645534, 5902.527797776189, 5913.438223368795, 5019.146745053313, 5199.714015729133, 5276.261323414725, 5901.232839730829, 5915.763369465195, 5185.325907632021, 5208.166693329087, 5224.059669259532, 5227.634065615409, 5228.92156617651, 13166.73666526664, 15025.173615451145, 21331.146754129815, 21402.250921217837, 21489.0, 1738.2873022060894, 1824.1700026487915, 1920.596674698315, 1927.441042097804, 2119.671450925109, 9214.401063930824, 9694.36986595869, 9696.903030969741, 9734.41593504385, 13154.0, 1725.7325114607256, 1774.1838754173095, 1787.30295184878, 1810.2125575374623, 1825.7900341460636, 71398.0, 73371.26628733697, 74334.25665743327, 76706.61646691787, 77565.0, 9761.658341957997, 10113.64602238918, 10158.206493214426, 10212.285140040125, 10440.0, 14012.719745605234, 14031.719365612631, 14036.508722194674, 14045.85954140466, 14053.22707251111, 7042.6830792614455, 7177.820554486179, 7237.8552428951125, 7255.56466612005, 7319.268073192638, 6571.408573228432, 6630.933690663128, 6776.966115169491, 6818.28408017161, 6824.317568243136, 4242.766647834399, 4317.56824317572, 4337.917799456216, 4443.800028998703, 4458.308962110878, 1773.0, 1839.3286450445758, 1848.5286252005653, 1865.766779152606, 1881.595456976729, 737.2553720742089, 846.6401779243797, 851.3657325292696, 853.9444936079135, 886.0652012127139, 16920.238589263266, 16991.0, 17212.70904682129, 17383.391581294592, 18154.90922545379, 831.3806214370333, 894.4230971023784, 899.1008991008991, 909.0591238068567, 911.5214512381053, 9591.760205994766, 10126.746831329128, 10152.746181345525, 10161.4919254037, 10205.38767673942, 432185.0, 436126.0, 443316.0, 444994.0, 447173.0, 85488.14511854803, 86611.0, 87738.0, 87913.56043219742, 88035.0, 916.8395530782086, 948.9905100948902, 981.6858605246252, 981.7496538382667, 984.6553706202772, 15725.606859828138, 15951.920240399188, 16193.75709364384, 16299.429519966745, 16699.415520456725, 38953.22093558056, 39453.210935781695, 39573.0, 39840.60159398369, 39876.2024759509, 54079.72960135174, 54447.72776136094, 54958.72520637371, 55425.168622469886, 55982.44017559772, 7470.813229669302, 7579.431542634435, 7598.696052157852, 7636.8472630548185, 8128.63421146038, 6529.771457998947, 8525.658973640984, 10771.19216058783, 11399.316041037233, 11406.48670809799, 9893.0, 9908.801823963337, 9986.300958932805, 10173.338732982333, 10327.638532651321, 9385.202257808163, 10085.848712269175, 10116.645917392856, 10207.540660670436, 10297.3821570706, 9247.583858726239, 9405.20055795265, 9840.655577055055, 10399.376037437778, 10715.142788577052, 7505.362044226302, 7527.0, 7615.771526854203, 7645.388368930583, 7797.376209903307, 9425.481598512148, 9875.555599997873, 10170.288079834341, 10224.53989570486, 10282.948585257025], "value": 8288.980712027676, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1260.350919276572, 1379.1656048090902, 1430.864067913548, 1506.47273454291, 1684.8230935751737, 6710.597364158154, 7363.226861179573, 7703.614819259043, 7706.6146692665425, 7707.0, 7370.4472164587605, 7509.812254693636, 7510.624468776553, 7691.5000524965835, 7695.192004839495, 2117.1213946212342, 2165.2421652421604, 2167.6856855755987, 2167.945801354963, 2168.544605632819, 2116.608427440928, 2158.719366482355, 2173.8369622278315, 2177.4991751897073, 2179.814715749163, 2137.487003119256, 2150.6343921533376, 2159.92003998001, 2160.643493823525, 2172.4134483689395, 2109.050927082811, 2117.3436234767273, 2121.554473560554, 2122.1299267300333, 2124.3733098735756, 2081.157131361801, 2129.829613630906, 2131.0836340373635, 2132.104516103225, 2137.262644387684, 7520.059992500937, 7742.380609551223, 7752.961235193844, 7762.379009679212, 7789.532628042336, 4508.188526065307, 4691.718496890164, 4695.812167513295, 4695.88260293492, 4697.506761790031, 2751.9587206191923, 2861.2989817494845, 2889.8699558519916, 2910.3888183481495, 2911.679715231323, 910.5128756115447, 911.5032307392421, 926.703454894434, 933.2207606648457, 951.0346997797242, 10587.417692026935, 10731.35611863283, 10742.677719668422, 10771.0, 10777.730556735987, 3948.506436695413, 4163.084980908755, 4250.086231460251, 4253.553376895444, 4265.402843601898, 9248.028956959422, 9264.027277135938, 9272.0, 9292.628294868266, 9293.210077143518, 4626.583607475276, 4629.953700463019, 4636.281376386665, 4645.3032045193495, 4649.604783593365, 3722.181120153562, 4118.258713431551, 4190.5180904195795, 4199.286121359376, 4293.484781826201, 3664.6152154023443, 3704.7959413190283, 3805.847766089381, 3855.325318069383, 3865.2076324354002, 1191.4281145050425, 1194.5759255464359, 1199.3463562358536, 1219.5731493977037, 1231.975360492803, 826.4751882554501, 849.6856163219649, 854.0775961961067, 871.7689812199773, 886.8536691445968, 5131.461196574306, 5293.523582877559, 5358.490943360374, 5394.973025134849, 5404.351477822608, 403.57221345373995, 406.638092098033, 409.01223545138157, 410.20625090450073, 422.52043930139513, 3655.6709896108946, 3728.4593733908973, 3754.1928485375774, 3760.1351689111657, 3779.9433008504893, 9549.713508594754, 9834.655787047419, 9952.054554817281, 9984.0, 10110.39337639744, 9908.455034973002, 9923.950380248198, 9927.900720992842, 9928.702138935843, 9931.205503559842, 7379.446541509408, 7584.2794934481135, 7592.658330375147, 7599.202083781123, 7602.201768814195, 5667.2349232854685, 5729.427057294237, 5756.510696590837, 5815.94184058154, 5865.296164460292, 9229.307801915018, 9321.580528876082, 9327.906720932704, 9333.300002499704, 9335.953320233355, 9107.681231157143, 9166.816663666823, 9330.720078397659, 9331.066893310615, 9420.764480888032, 5730.312362516358, 6592.67036648156, 6731.158605174353, 6792.422644075309, 7058.188308344606, 7373.5575865448245, 7486.513376630502, 7647.197044310267, 7669.6548655309525, 7673.961630191813, 6495.805125846232, 6511.7395304189195, 6530.8040758777315, 6659.633720145439, 6734.696938637675, 6465.515086368635, 6497.805065848032, 6506.121673574007, 6555.541112122106, 6563.310852360624, 6131.202943617302, 6256.874862502816, 6261.812145635638, 6265.27949285838, 6267.435930766071, 4574.336721175478, 4743.881402964954, 4793.0, 4835.032993401263, 4840.757962101951, 4668.393108895822, 4831.275308703582, 4856.68431551948, 4870.147724148189, 4930.531599498042], "value": 4085.1012117601454, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.2114164061604, 277.8013720190064, 280.5371137622923, 284.3460041903621, 284.6768917278888, 4849.709017458955, 4890.804367825292, 4900.921797204618, 4907.509249075095, 4929.412058669201, 4552.226121559334, 4693.460252071013, 4707.317438971347, 4709.199436095863, 4862.927056094161, 3684.392075307571, 3688.5758137814228, 3694.2426802505556, 3742.6070262622443, 3802.6007269236748, 3789.5073640426845, 3820.847166113352, 3821.388577827548, 3827.947314488524, 3829.215010922768, 3666.6883314918264, 3802.1065049713297, 3807.086299288165, 3819.9427008594753, 3820.426935959614, 3538.7876727396183, 3660.3777357848962, 3761.097336639187, 3792.829322680472, 3811.104390468238, 3332.483465062919, 3345.9498107528407, 3350.681685239923, 3361.159710072482, 3365.2428203654226, 4725.267583524559, 4732.219183834663, 4734.976325118352, 4738.24188129901, 4765.928511072337, 4325.286327755916, 4328.696991210617, 4334.176506463762, 4362.498312694049, 4364.388985542027, 4826.61387089032, 5045.520675535855, 5069.949300507022, 5087.516685914832, 5089.618278629117, 3628.0952499937744, 3663.5528966058523, 3674.0, 3680.4663323818163, 3684.6315368463206, 5296.761645725913, 5323.946760532383, 5326.573874090063, 5340.25236466895, 5361.705106219196, 3564.0733409313248, 3740.3454395481226, 3785.3943369060817, 3800.3349413853016, 3801.6958643308467, 3842.423636454554, 3849.191669749384, 3855.248226595843, 3865.468118382464, 3865.942010869839, 4363.607275345171, 4378.956210437855, 4383.364412160283, 4384.386185933972, 4405.502129276061, 4925.729084900293, 5044.672096313728, 5057.7724002419245, 5192.221166825007, 5193.740312984336, 1308.6074177746827, 1317.8155058291848, 1326.3036905624624, 1326.608650448101, 1327.482281910056, 833.5457175839182, 844.344536432915, 859.3426029087752, 865.0268447996004, 871.5250188647202, 4021.778802165909, 4029.5567487576345, 4035.8991025224605, 4047.170330082326, 4063.7968101595393, 4627.95870929045, 4665.300204969281, 4668.85993420198, 4672.392588963481, 4682.601978831769, 3990.68074554035, 3993.580674029185, 4026.053877338823, 4039.6162364575907, 4063.89840253996, 2499.8250122491613, 2588.65726517502, 2782.930426739307, 2796.2310364649884, 2798.768541841583, 4839.951600484021, 4850.054239423349, 4875.463698993108, 4884.462709101996, 4885.438174609894, 5339.519443250126, 5348.304720386325, 5352.839414817562, 5362.597805164551, 5394.002109609734, 4648.58162765346, 4857.344258525054, 4935.062338155739, 4938.38270216223, 4953.578945789648, 5226.843194704165, 5241.659292145999, 5253.789848406021, 5257.6056795739705, 5314.97342513285, 4919.754012299442, 5184.781576329635, 5194.948050519446, 5216.2436446714255, 5230.843074707765, 4870.0, 4889.975550122226, 4890.364252647133, 4894.265860120868, 4903.5586797188425, 3585.300866331145, 3608.4587311903424, 3616.65641764032, 3627.709783217389, 3631.945520817137, 5143.408508021475, 5233.188855727369, 5244.868878277921, 5290.28581141541, 5293.470652934676, 5046.243063540498, 5110.284560161655, 5115.590752739847, 5133.845984620468, 5133.8716532087, 5101.897962040664, 5104.183330667075, 5118.129917914038, 5119.28330033803, 5122.334096567422, 4901.558859702502, 4907.460179380194, 5021.698698077981, 5030.522100400456, 5041.344625198701, 4906.779194936165, 5060.3927528696795, 5068.113080211022, 5128.820491282787, 5177.4822517749435, 4876.244182151772, 4932.926006109839, 4953.950460495349, 4955.975220123876, 4956.628252881121], "value": 3789.2129616276557, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.5709260961669, 590.988180236395, 632.2634131237108, 633.7781776378267, 646.789793317172, 12539.31033793141, 13180.340982950851, 13182.670433239175, 13665.863341366608, 13707.246101464416, 11434.942825285883, 12011.27932324059, 12058.09564282678, 12179.695507612292, 12246.0815438842, 755.6939439526986, 768.5964868444083, 769.5959621198859, 769.9461037727361, 770.445279398832, 768.8616049111158, 771.8301973565807, 772.3357912195536, 772.7256823827545, 773.0993392697501, 805.2913436176157, 806.5160903457908, 806.9919300807005, 807.2371608829658, 807.2694211738386, 798.0264077825044, 799.5003123048095, 799.5442597719332, 799.7001124578284, 800.315730050804, 585.6398315036265, 586.1208187718422, 586.5542187937156, 586.712510869675, 586.8620874094585, 11335.943320283346, 11523.366214858179, 11537.94231028848, 11542.0, 11575.42122893852, 10167.694969150863, 10295.279330446872, 10439.164866810637, 10791.352518848895, 10879.456027198608, 9622.566984485715, 9693.854592181124, 9792.314537982344, 9872.60509579616, 9882.555285012191, 3151.4169878572307, 3177.984110079458, 3179.1257404213793, 3209.5988001499813, 3218.4045951499047, 51267.23099153516, 52456.21315680306, 59338.0, 59892.401075989554, 60003.099953500736, 10240.180785537139, 10429.843552346721, 10589.682309530726, 10689.144868410509, 10734.57061717538, 18499.0, 18541.0, 18654.34709785151, 18886.05569721536, 18900.0, 6666.366695163952, 6806.7957961261245, 6827.419669328064, 6912.412444942136, 7196.0, 3683.7605555638797, 3770.7737535747942, 3771.26460340231, 3787.394016957274, 3845.8846234612447, 2791.260316016257, 2844.1751891951517, 2845.0, 2860.985695071511, 2879.827210367385, 507.2163507381105, 508.659198337115, 518.6732358614124, 522.5741021067894, 525.9211118332204, 500.669558091665, 517.5161224255334, 539.5359990408245, 585.3239508367842, 598.1565991951328, 7640.923590764021, 7832.84334313322, 8010.879836802453, 8024.157463466368, 8252.711155109662, 473.3704173449373, 521.72609380075, 523.2595876834237, 530.9469053094737, 541.8834950485665, 3806.0865392305645, 3947.3092208863336, 3948.4274780156716, 4037.6769858411258, 4095.365218391154, 93650.0, 93675.53162234281, 93758.53120734352, 94149.5292523533, 96524.0, 79639.0, 80174.599127004, 80242.19757802464, 82739.0, 83232.58383708043, 44052.55947440484, 67750.32249677539, 69429.3057069433, 70367.99999999898, 73027.0, 8264.21489958453, 8883.600237989176, 9086.681966131153, 9135.817283654422, 9296.535173241446, 43508.0, 43662.0, 44606.76966151615, 46091.53908461006, 46345.0, 54846.11392758728, 65743.0, 65944.67027665023, 65969.34030659824, 66679.0, 11439.714007149554, 11878.0, 11914.16600837933, 12100.152989290666, 12191.939040304742, 12544.937275313565, 12881.742365152832, 13362.198268103944, 13417.396217170055, 13527.188368697909, 11503.482343294405, 11583.884161158618, 11585.131115166228, 11767.646970590897, 12110.45502952387, 11996.100292477924, 12439.315837629018, 12443.502259909503, 12475.064370172455, 12519.374031298217, 10639.344506665591, 11540.769184616092, 12021.0, 12027.939860300992, 12029.879701202875, 9463.716088517356, 9516.476593787409, 9696.205422527159, 9880.209583233467, 9890.703278901645, 9070.410423322463, 10125.291229613857, 10203.132733717717, 10219.642312519027, 10267.589296428358], "value": 6396.001717364219, "unit": "score"}], "name": "dromaeo_css", "value": 6419.18480770753, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 113.0, 114.0, 115.0, 114.0, 113.0, 116.0, 113.0, 114.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 123.0, 191.0, 124.0, 127.0, 155.0, 123.0, 164.0, 124.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 160.0, 162.0, 160.0, 161.0, 160.0, 163.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 126.0, 76.0, 75.0, 75.0, 76.0, 104.0, 75.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 117.0, 85.0, 85.0, 85.0, 122.0, 84.0, 85.0, 126.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 148.0, 146.0, 147.0, 146.0, 148.0, 148.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 61.0, 61.0, 60.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 64.0, 65.0, 64.0, 66.0, 66.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 79.0, 79.0, 80.0, 89.0, 79.0, 79.0, 83.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 137.0, 130.0, 131.0, 126.0, 131.0, 127.0, 129.0, 176.0, 132.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 165.0, 164.0, 169.0, 165.0, 165.0, 168.0, 162.0, 162.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 67.0, 65.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1525.0, "alertThreshold": 2.0}]} 10:25:58 INFO - Return code: 0 10:25:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:25:58 INFO - # TBPL SUCCESS # 10:25:58 INFO - Running post-action listener: _resource_record_post_action 10:25:58 INFO - [mozharness: 2016-07-22 17:25:58.452285Z] Finished run-tests step (success) 10:25:58 INFO - Running post-run listener: _resource_record_post_run 10:25:58 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 547762176; Read time: 12; Write time: 645336 10:25:58 INFO - TinderboxPrint: CPU usage
13.0% 10:25:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:25:58 INFO - TinderboxPrint: I/O write bytes / time
547,762,176 / 645,336 10:25:58 INFO - TinderboxPrint: CPU idle
8,345.0 (87.0%) 10:25:58 INFO - TinderboxPrint: CPU user
1,199.3 (12.5%) 10:25:58 INFO - TinderboxPrint: Swap in / out
0 / 0 10:25:58 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 250351616; Read time: 0; Write time: 340180 10:25:58 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 297115648; Read time: 0; Write time: 305108 10:25:59 INFO - Running post-run listener: _upload_blobber_files 10:25:59 INFO - Blob upload gear active. 10:25:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:25:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:25:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:25:59 INFO - (blobuploader) - INFO - Open directory for files ... 10:25:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:25:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:26:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:22 INFO - (blobuploader) - INFO - Done attempting. 10:26:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:26:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:26:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:23 INFO - (blobuploader) - INFO - Done attempting. 10:26:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:26:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:26:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:24 INFO - (blobuploader) - INFO - Done attempting. 10:26:24 INFO - (blobuploader) - INFO - Iteration through files over. 10:26:24 INFO - Return code: 0 10:26:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:26:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:26:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a7d71ba8c453ca90e79da1595eb14111303f9d0166f8c77ff110f6f61b7570c4256ae46faa02b91b5421ab38ffb39d77ce83ed12dd045083aa7cb5b2b3edd508", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b7a3db9a7ae4455d3e576209ff2ca3bcd917c36e36d8e0de12b31c48a9aa260d2423c6a347230214eeb3c18195d03400f14229ac149a80e79b63fb4478bd37d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31af07d6b195b01472a8cda828921a76e48ace9a7d6ed52e60d72fca67e03894fda776a5dd020e32c8e2cc47c70e1935655d0df15c6ff7ee4ec2ed0054298a40"} 10:26:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:26:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:26:24 INFO - Contents: 10:26:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a7d71ba8c453ca90e79da1595eb14111303f9d0166f8c77ff110f6f61b7570c4256ae46faa02b91b5421ab38ffb39d77ce83ed12dd045083aa7cb5b2b3edd508", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b7a3db9a7ae4455d3e576209ff2ca3bcd917c36e36d8e0de12b31c48a9aa260d2423c6a347230214eeb3c18195d03400f14229ac149a80e79b63fb4478bd37d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31af07d6b195b01472a8cda828921a76e48ace9a7d6ed52e60d72fca67e03894fda776a5dd020e32c8e2cc47c70e1935655d0df15c6ff7ee4ec2ed0054298a40"} 10:26:24 INFO - Running post-run listener: copy_logs_to_upload_dir 10:26:24 INFO - Copying logs to upload dir... 10:26:24 INFO - mkdir: /builds/slave/test/build/upload/logs 10:26:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.513279 ========= master_lag: 0.54 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-07-22 10:26:25.198003) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 10:26:25.201464) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a7d71ba8c453ca90e79da1595eb14111303f9d0166f8c77ff110f6f61b7570c4256ae46faa02b91b5421ab38ffb39d77ce83ed12dd045083aa7cb5b2b3edd508", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b7a3db9a7ae4455d3e576209ff2ca3bcd917c36e36d8e0de12b31c48a9aa260d2423c6a347230214eeb3c18195d03400f14229ac149a80e79b63fb4478bd37d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31af07d6b195b01472a8cda828921a76e48ace9a7d6ed52e60d72fca67e03894fda776a5dd020e32c8e2cc47c70e1935655d0df15c6ff7ee4ec2ed0054298a40"} build_url:https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011260 build_url: 'https://queue.taskcluster.net/v1/task/ECj5zFg3QX2dn-SWvYAZng/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a7d71ba8c453ca90e79da1595eb14111303f9d0166f8c77ff110f6f61b7570c4256ae46faa02b91b5421ab38ffb39d77ce83ed12dd045083aa7cb5b2b3edd508", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b7a3db9a7ae4455d3e576209ff2ca3bcd917c36e36d8e0de12b31c48a9aa260d2423c6a347230214eeb3c18195d03400f14229ac149a80e79b63fb4478bd37d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31af07d6b195b01472a8cda828921a76e48ace9a7d6ed52e60d72fca67e03894fda776a5dd020e32c8e2cc47c70e1935655d0df15c6ff7ee4ec2ed0054298a40"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 10:26:25.230511) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:26:25.230879) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1469207121.137386-432552571 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004539 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 10:26:25.281291) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 10:26:25.281772) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 10:26:25.282175) ========= ========= Total master_lag: 0.67 =========