builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1476835858.84 results: success (0) buildid: 20161018141823 builduid: c980de31aafc4fab9477c6280b7bc0a2 revision: 11ba9af07367fa76691961289d5e46d880a868e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:58.839215) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:58.839712) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:58.839997) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:58.859346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:58.859709) ========= 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=0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-18 17:10:59-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 20.5M=0.001s 2016-10-18 17:10:59 (20.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.168343 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:59.046614) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:59.046975) ========= 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=0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018844 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:59.097670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:59.098040) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 11ba9af07367fa76691961289d5e46d880a868e8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 11ba9af07367fa76691961289d5e46d880a868e8 --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=0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-18 17:10:59,740 truncating revision to first 12 chars 2016-10-18 17:10:59,740 Setting DEBUG logging. 2016-10-18 17:10:59,740 attempt 1/10 2016-10-18 17:10:59,740 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/11ba9af07367?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 17:11:00,160 unpacking tar archive at: mozilla-beta-11ba9af07367/testing/mozharness/ program finished with exit code 0 elapsedTime=0.742437 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:59.854225) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:59.854542) ========= script_repo_revision: 11ba9af07367fa76691961289d5e46d880a868e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:59.854958) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:59.855255) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 17:10:59.866021) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-18 17:10:59.866455) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:11:00 INFO - MultiFileLogger online at 20161018 17:11:00 in /builds/slave/test 17:11:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:11:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:11:00 INFO - {'append_to_log': False, 17:11:00 INFO - 'base_work_dir': '/builds/slave/test', 17:11:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:11:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:11:00 INFO - 'branch': 'Mozilla-Beta', 17:11:00 INFO - 'buildbot_json_path': 'buildprops.json', 17:11:00 INFO - 'config_files': ('talos/linux_config.py',), 17:11:00 INFO - 'default_actions': ('clobber', 17:11:00 INFO - 'read-buildbot-config', 17:11:00 INFO - 'download-and-extract', 17:11:00 INFO - 'populate-webroot', 17:11:00 INFO - 'create-virtualenv', 17:11:00 INFO - 'install', 17:11:00 INFO - 'run-tests'), 17:11:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:11:00 INFO - 'download_minidump_stackwalk': True, 17:11:00 INFO - 'download_symbols': 'ondemand', 17:11:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:11:00 INFO - 'tooltool.py': '/tools/tooltool.py', 17:11:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:11:00 INFO - '/tools/misc-python/virtualenv.py')}, 17:11:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:11:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:11:00 INFO - 'installer_path': 'installer.exe', 17:11:00 INFO - 'log_level': 'info', 17:11:00 INFO - 'log_name': 'talos', 17:11:00 INFO - 'log_to_console': True, 17:11:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:11:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:11:00 INFO - 'opt_config_files': (), 17:11:00 INFO - 'pip_index': False, 17:11:00 INFO - 'sps_profile': False, 17:11:00 INFO - 'sps_profile_interval': 0, 17:11:00 INFO - 'suite': 'dromaeojs', 17:11:00 INFO - 'system_bits': '32', 17:11:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:11:00 INFO - 'title': 'talos-linux64-ix-058', 17:11:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:11:00 INFO - 'use_talos_json': True, 17:11:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:11:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:11:00 INFO - 'work_dir': 'build'} 17:11:00 INFO - [mozharness: 2016-10-19 00:11:00.605094Z] Running clobber step. 17:11:00 INFO - Running pre-action listener: _resource_record_pre_action 17:11:00 INFO - Running main action method: clobber 17:11:00 INFO - rmtree: /builds/slave/test/build 17:11:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:11:01 INFO - Running post-action listener: _resource_record_post_action 17:11:01 INFO - [mozharness: 2016-10-19 00:11:01.061341Z] Finished clobber step (success) 17:11:01 INFO - [mozharness: 2016-10-19 00:11:01.061443Z] Running read-buildbot-config step. 17:11:01 INFO - Running pre-action listener: _resource_record_pre_action 17:11:01 INFO - Running main action method: read_buildbot_config 17:11:01 INFO - Using buildbot properties: 17:11:01 INFO - { 17:11:01 INFO - "project": "", 17:11:01 INFO - "product": "firefox", 17:11:01 INFO - "script_repo_revision": "production", 17:11:01 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:11:01 INFO - "repository": "", 17:11:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:11:01 INFO - "buildid": "20161018141823", 17:11:01 INFO - "pgo_build": "True", 17:11:01 INFO - "basedir": "/builds/slave/test", 17:11:01 INFO - "buildnumber": 196, 17:11:01 INFO - "slavename": "talos-linux64-ix-058", 17:11:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:11:01 INFO - "platform": "ubuntu64_hw", 17:11:01 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:11:01 INFO - "branch": "mozilla-beta", 17:11:01 INFO - "repo_path": "releases/mozilla-beta", 17:11:01 INFO - "revision": "11ba9af07367fa76691961289d5e46d880a868e8", 17:11:01 INFO - "stage_platform": "linux64-pgo", 17:11:01 INFO - "builduid": "c980de31aafc4fab9477c6280b7bc0a2", 17:11:01 INFO - "slavebuilddir": "test" 17:11:01 INFO - } 17:11:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 17:11:01 INFO - Running post-action listener: _resource_record_post_action 17:11:01 INFO - [mozharness: 2016-10-19 00:11:01.063890Z] Finished read-buildbot-config step (success) 17:11:01 INFO - [mozharness: 2016-10-19 00:11:01.063980Z] Running download-and-extract step. 17:11:01 INFO - Running pre-action listener: _resource_record_pre_action 17:11:01 INFO - Running main action method: download_and_extract 17:11:01 INFO - mkdir: /builds/slave/test/build/tests 17:11:01 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')]} 17:11:01 INFO - https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:11:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:11:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:11:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:11:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:11:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:11:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:11:01 INFO - retry: Failed, sleeping 30 seconds before retrying 17:11:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 17:11:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:11:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:11:31 INFO - retry: Failed, sleeping 60 seconds before retrying 17:12:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 17:12:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:12:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:12:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 17:12:31 INFO - Caught exception: HTTP Error 404: Not Found 17:12:31 INFO - Caught exception: HTTP Error 404: Not Found 17:12:31 INFO - Caught exception: HTTP Error 404: Not Found 17:12:31 INFO - trying https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:12:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:12:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:12:31 INFO - Downloaded 1327 bytes. 17:12:31 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:12:31 INFO - Using the following test package requirements: 17:12:31 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:12:31 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:12:31 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:12:31 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:12:31 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 17:12:31 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:12:31 INFO - u'jsshell-linux-x86_64.zip'], 17:12:31 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:12:31 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:12:31 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:12:31 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:12:31 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 17:12:31 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:12:31 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 17:12:31 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:12:31 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:12:31 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:12:31 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:12:31 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:12: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')]} 17:12:31 INFO - https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:12:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:12:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:12:31 INFO - retry: Failed, sleeping 30 seconds before retrying 17:13:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:13:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:13:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:13:01 INFO - retry: Failed, sleeping 60 seconds before retrying 17:14:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:14:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:14:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:14:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:14:01 INFO - Caught exception: HTTP Error 404: Not Found 17:14:01 INFO - Caught exception: HTTP Error 404: Not Found 17:14:01 INFO - Caught exception: HTTP Error 404: Not Found 17:14:01 INFO - trying https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:14:01 INFO - Downloading https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:14:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:14:02 INFO - Downloaded 21774988 bytes. 17:14:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:14:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:14:03 INFO - Return code: 0 17:14:03 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:14:03 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')]} 17:14:03 INFO - https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:14:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:14:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:14:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:14:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:14:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:14:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:14:03 INFO - retry: Failed, sleeping 30 seconds before retrying 17:14:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:14:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:14:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:14:33 INFO - retry: Failed, sleeping 60 seconds before retrying 17:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:15:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:15:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:15:33 INFO - Caught exception: HTTP Error 404: Not Found 17:15:33 INFO - Caught exception: HTTP Error 404: Not Found 17:15:33 INFO - Caught exception: HTTP Error 404: Not Found 17:15:33 INFO - trying https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:33 INFO - Downloading https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:15:34 INFO - Downloaded 21774988 bytes. 17:15:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:15:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:15:36 INFO - Return code: 0 17:15: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')]} 17:15:36 INFO - https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:15:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:15:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:15:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:15:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:15:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:15:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:15:36 INFO - retry: Failed, sleeping 30 seconds before retrying 17:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 17:16:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:16:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:16:06 INFO - retry: Failed, sleeping 60 seconds before retrying 17:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 17:17:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:17:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:17:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 17:17:06 INFO - Caught exception: HTTP Error 404: Not Found 17:17:06 INFO - Caught exception: HTTP Error 404: Not Found 17:17:06 INFO - Caught exception: HTTP Error 404: Not Found 17:17:06 INFO - trying https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:17:06 INFO - Downloading https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:17:07 INFO - Downloaded 14413762 bytes. 17:17:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:17:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:17:07 INFO - Return code: 0 17:17:07 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')]} 17:17:07 INFO - https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:17:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:17:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:17:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:17:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:17:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:17:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:17:07 INFO - retry: Failed, sleeping 30 seconds before retrying 17:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 17:17:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:17:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:17:37 INFO - retry: Failed, sleeping 60 seconds before retrying 17:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 17:18:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:18:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 17:18:37 INFO - Caught exception: HTTP Error 404: Not Found 17:18:37 INFO - Caught exception: HTTP Error 404: Not Found 17:18:37 INFO - Caught exception: HTTP Error 404: Not Found 17:18:37 INFO - trying https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:37 INFO - Downloading https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:18:38 INFO - Downloaded 56804657 bytes. 17:18:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:38 INFO - mkdir: /builds/slave/test/properties 17:18:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:18:38 INFO - Writing to file /builds/slave/test/properties/build_url 17:18:38 INFO - Contents: 17:18:38 INFO - build_url:https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:39 INFO - Running post-action listener: _resource_record_post_action 17:18:39 INFO - Running post-action listener: set_extra_try_arguments 17:18:39 INFO - [mozharness: 2016-10-19 00:18:39.176277Z] Finished download-and-extract step (success) 17:18:39 INFO - [mozharness: 2016-10-19 00:18:39.176509Z] Running populate-webroot step. 17:18:39 INFO - Running pre-action listener: _resource_record_pre_action 17:18:39 INFO - Running main action method: populate_webroot 17:18:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:18:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:18:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:18:39 INFO - '--tppagecycles', 17:18:39 INFO - '7'], 17:18:39 INFO - 'tests': ['tsvgm']}}, 17:18:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:18:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:18:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:18:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:18:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:18:39 INFO - 'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:18:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:18:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:18:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:18:39 INFO - 'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['damp', 'tps']}, 17:18:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:18:39 INFO - 'tests': ['damp', 'tps']}, 17:18:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['dromaeo_dom']}, 17:18:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:18:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['basic_compositor_video']}, 17:18:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:18:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['a11yr', 17:18:39 INFO - 'ts_paint', 17:18:39 INFO - 'tpaint', 17:18:39 INFO - 'sessionrestore', 17:18:39 INFO - 'sessionrestore_no_auto_restore', 17:18:39 INFO - 'tabpaint']}, 17:18:39 INFO - 'other-e10s': {'tests': ['a11yr', 17:18:39 INFO - 'ts_paint', 17:18:39 INFO - 'tpaint', 17:18:39 INFO - 'sessionrestore', 17:18:39 INFO - 'sessionrestore_no_auto_restore', 17:18:39 INFO - 'tabpaint']}, 17:18:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:18:39 INFO - 'ts_paint', 17:18:39 INFO - 'tpaint', 17:18:39 INFO - 'sessionrestore', 17:18:39 INFO - 'sessionrestore_no_auto_restore', 17:18:39 INFO - 'tabpaint']}, 17:18:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:18:39 INFO - 'ts_paint', 17:18:39 INFO - 'tpaint', 17:18:39 INFO - 'sessionrestore', 17:18:39 INFO - 'sessionrestore_no_auto_restore', 17:18:39 INFO - 'tabpaint']}, 17:18:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['a11yr', 17:18:39 INFO - 'ts_paint', 17:18:39 INFO - 'tpaint', 17:18:39 INFO - 'sessionrestore', 17:18:39 INFO - 'sessionrestore_no_auto_restore', 17:18:39 INFO - 'tabpaint']}, 17:18:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['a11yr', 17:18:39 INFO - 'ts_paint', 17:18:39 INFO - 'tpaint', 17:18:39 INFO - 'sessionrestore', 17:18:39 INFO - 'sessionrestore_no_auto_restore', 17:18:39 INFO - 'tabpaint']}, 17:18:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['tsvgx', 17:18:39 INFO - 'tsvgr_opacity', 17:18:39 INFO - 'tart', 17:18:39 INFO - 'tscrollx', 17:18:39 INFO - 'cart']}, 17:18:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:18:39 INFO - 'tsvgr_opacity', 17:18:39 INFO - 'tart', 17:18:39 INFO - 'tscrollx', 17:18:39 INFO - 'cart']}, 17:18:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:18:39 INFO - 'talos_options': ['--disable-e10s'], 17:18:39 INFO - 'tests': ['tp5o']}, 17:18:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:18:39 INFO - 'tests': ['tp5o']}, 17:18:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:18:39 INFO - 'talos_options': ['--disable-e10s', 17:18:39 INFO - '--xperf_path', 17:18:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:18:39 INFO - 'tests': ['tp5n']}, 17:18:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:18:39 INFO - 'talos_options': ['--xperf_path', 17:18:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:18:39 INFO - 'tests': ['tp5n']}}, 17:18:39 INFO - 'talos.zip': {'path': '', 17:18:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:18:39 INFO - Running post-action listener: _resource_record_post_action 17:18:39 INFO - [mozharness: 2016-10-19 00:18:39.194561Z] Finished populate-webroot step (success) 17:18:39 INFO - [mozharness: 2016-10-19 00:18:39.194650Z] Running create-virtualenv step. 17:18:39 INFO - Running pre-action listener: _resource_record_pre_action 17:18:39 INFO - Running main action method: create_virtualenv 17:18:39 INFO - Creating virtualenv /builds/slave/test/build/venv 17:18:39 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 17:18:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:18:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:18:39 INFO - Using real prefix '/usr' 17:18:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:18:39 INFO - Installing distribute.............................................................................................................................................................................................done. 17:18:41 INFO - Installing pip.................done. 17:18:41 INFO - Return code: 0 17:18:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:18: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')]} 17:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18: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')]} 17:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:18: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', 'pip>=1.5'] in /builds/slave/test/build 17:18: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 pip>=1.5 17:18:41 INFO - Using env: {'DISPLAY': ':0', 17:18:41 INFO - 'HOME': '/home/cltbld', 17:18:41 INFO - 'LANG': 'en_US.UTF-8', 17:18:41 INFO - 'LANGUAGE': 'en_US:en', 17:18:41 INFO - 'LOGNAME': 'cltbld', 17:18:41 INFO - 'MAIL': '/var/mail/cltbld', 17:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:41 INFO - 'MOZ_NO_REMOTE': '1', 17:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:41 INFO - 'NO_EM_RESTART': '1', 17:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:41 INFO - 'PWD': '/builds/slave/test', 17:18:41 INFO - 'SHELL': '/bin/bash', 17:18:41 INFO - 'SHLVL': '1', 17:18:41 INFO - 'TERM': 'linux', 17:18:41 INFO - 'TMOUT': '86400', 17:18:41 INFO - 'USER': 'cltbld', 17:18:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:41 INFO - '_': '/tools/buildbot/bin/python'} 17:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:41 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 17:18:41 INFO - Cleaning up... 17:18:41 INFO - Return code: 0 17:18:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:18: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')]} 17:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18: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')]} 17:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18: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', '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 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:18: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', 'psutil>=3.1.1'] in /builds/slave/test/build 17:18: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 psutil>=3.1.1 17:18:41 INFO - Using env: {'DISPLAY': ':0', 17:18:41 INFO - 'HOME': '/home/cltbld', 17:18:41 INFO - 'LANG': 'en_US.UTF-8', 17:18:41 INFO - 'LANGUAGE': 'en_US:en', 17:18:41 INFO - 'LOGNAME': 'cltbld', 17:18:41 INFO - 'MAIL': '/var/mail/cltbld', 17:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:41 INFO - 'MOZ_NO_REMOTE': '1', 17:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:41 INFO - 'NO_EM_RESTART': '1', 17:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:41 INFO - 'PWD': '/builds/slave/test', 17:18:41 INFO - 'SHELL': '/bin/bash', 17:18:41 INFO - 'SHLVL': '1', 17:18:41 INFO - 'TERM': 'linux', 17:18:41 INFO - 'TMOUT': '86400', 17:18:41 INFO - 'USER': 'cltbld', 17:18:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:41 INFO - '_': '/tools/buildbot/bin/python'} 17:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:42 INFO - Downloading/unpacking psutil>=3.1.1 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:18:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:18:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:18:44 INFO - Installing collected packages: psutil 17:18:44 INFO - Running setup.py install for psutil 17:18:44 INFO - building 'psutil._psutil_linux' extension 17:18:44 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 17:18:44 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 17:18:44 INFO - building 'psutil._psutil_posix' extension 17:18:44 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 17:18:44 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 17:18:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:18:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:18:44 INFO - Successfully installed psutil 17:18:44 INFO - Cleaning up... 17:18:44 INFO - Return code: 0 17:18:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:18: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')]} 17:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18: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')]} 17:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:18: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:18: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 mozsystemmonitor==0.3 17:18:44 INFO - Using env: {'DISPLAY': ':0', 17:18:44 INFO - 'HOME': '/home/cltbld', 17:18:44 INFO - 'LANG': 'en_US.UTF-8', 17:18:44 INFO - 'LANGUAGE': 'en_US:en', 17:18:44 INFO - 'LOGNAME': 'cltbld', 17:18:44 INFO - 'MAIL': '/var/mail/cltbld', 17:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:44 INFO - 'MOZ_NO_REMOTE': '1', 17:18:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:44 INFO - 'NO_EM_RESTART': '1', 17:18:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:44 INFO - 'PWD': '/builds/slave/test', 17:18:44 INFO - 'SHELL': '/bin/bash', 17:18:44 INFO - 'SHLVL': '1', 17:18:44 INFO - 'TERM': 'linux', 17:18:44 INFO - 'TMOUT': '86400', 17:18:44 INFO - 'USER': 'cltbld', 17:18:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:44 INFO - '_': '/tools/buildbot/bin/python'} 17:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:18:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:18:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:18:46 INFO - Installing collected packages: mozsystemmonitor 17:18:46 INFO - Running setup.py install for mozsystemmonitor 17:18:47 INFO - Successfully installed mozsystemmonitor 17:18:47 INFO - Cleaning up... 17:18:47 INFO - Return code: 0 17:18:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:18:47 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')]} 17:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:47 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')]} 17:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:47 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 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:18:47 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 17:18:47 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 17:18:47 INFO - Using env: {'DISPLAY': ':0', 17:18:47 INFO - 'HOME': '/home/cltbld', 17:18:47 INFO - 'LANG': 'en_US.UTF-8', 17:18:47 INFO - 'LANGUAGE': 'en_US:en', 17:18:47 INFO - 'LOGNAME': 'cltbld', 17:18:47 INFO - 'MAIL': '/var/mail/cltbld', 17:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:47 INFO - 'MOZ_NO_REMOTE': '1', 17:18:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:47 INFO - 'NO_EM_RESTART': '1', 17:18:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:47 INFO - 'PWD': '/builds/slave/test', 17:18:47 INFO - 'SHELL': '/bin/bash', 17:18:47 INFO - 'SHLVL': '1', 17:18:47 INFO - 'TERM': 'linux', 17:18:47 INFO - 'TMOUT': '86400', 17:18:47 INFO - 'USER': 'cltbld', 17:18:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:47 INFO - '_': '/tools/buildbot/bin/python'} 17:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:47 INFO - Downloading/unpacking jsonschema==2.5.1 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:18:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:18:49 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 17:18:49 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 17:18:49 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 17:18:49 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 17:18:49 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 17:18:49 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 17:18:49 INFO - Downloading functools32-3.2.3-2.tar.gz 17:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:18:49 INFO - warning: no files found matching '*.txt' 17:18:49 INFO - no previously-included directories found matching 'build' 17:18:49 INFO - no previously-included directories found matching 'dist' 17:18:49 INFO - no previously-included directories found matching '.git*' 17:18:49 INFO - Installing collected packages: jsonschema, functools32 17:18:49 INFO - Running setup.py install for functools32 17:18:49 INFO - warning: no files found matching '*.txt' 17:18:49 INFO - no previously-included directories found matching 'build' 17:18:49 INFO - no previously-included directories found matching 'dist' 17:18:49 INFO - no previously-included directories found matching '.git*' 17:18:49 INFO - Successfully installed jsonschema functools32 17:18:49 INFO - Cleaning up... 17:18:49 INFO - Return code: 0 17:18:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:18:49 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')]} 17:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:49 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')]} 17:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:49 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:18:49 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:18:49 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 functools32==3.2.3-2 17:18:49 INFO - Using env: {'DISPLAY': ':0', 17:18:49 INFO - 'HOME': '/home/cltbld', 17:18:49 INFO - 'LANG': 'en_US.UTF-8', 17:18:49 INFO - 'LANGUAGE': 'en_US:en', 17:18:49 INFO - 'LOGNAME': 'cltbld', 17:18:49 INFO - 'MAIL': '/var/mail/cltbld', 17:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:49 INFO - 'MOZ_NO_REMOTE': '1', 17:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:49 INFO - 'NO_EM_RESTART': '1', 17:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:49 INFO - 'PWD': '/builds/slave/test', 17:18:49 INFO - 'SHELL': '/bin/bash', 17:18:49 INFO - 'SHLVL': '1', 17:18:49 INFO - 'TERM': 'linux', 17:18:49 INFO - 'TMOUT': '86400', 17:18:49 INFO - 'USER': 'cltbld', 17:18:49 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:49 INFO - '_': '/tools/buildbot/bin/python'} 17:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:18:49 INFO - Cleaning up... 17:18:49 INFO - Return code: 0 17:18:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:18:49 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')]} 17:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:49 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')]} 17:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:49 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 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:18:49 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 17:18:49 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 17:18:49 INFO - Using env: {'DISPLAY': ':0', 17:18:49 INFO - 'HOME': '/home/cltbld', 17:18:49 INFO - 'LANG': 'en_US.UTF-8', 17:18:49 INFO - 'LANGUAGE': 'en_US:en', 17:18:49 INFO - 'LOGNAME': 'cltbld', 17:18:49 INFO - 'MAIL': '/var/mail/cltbld', 17:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:49 INFO - 'MOZ_NO_REMOTE': '1', 17:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:49 INFO - 'NO_EM_RESTART': '1', 17:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:49 INFO - 'PWD': '/builds/slave/test', 17:18:49 INFO - 'SHELL': '/bin/bash', 17:18:49 INFO - 'SHLVL': '1', 17:18:49 INFO - 'TERM': 'linux', 17:18:49 INFO - 'TMOUT': '86400', 17:18:49 INFO - 'USER': 'cltbld', 17:18:49 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:49 INFO - '_': '/tools/buildbot/bin/python'} 17:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:50 INFO - Downloading/unpacking blobuploader==1.2.4 17:18:50 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 17:18:50 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 17:18:50 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 17:18:50 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 17:18:50 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 17:18:50 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 17:18:52 INFO - Downloading blobuploader-1.2.4.tar.gz 17:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:18:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:18:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:52 INFO - Downloading docopt-0.6.1.tar.gz 17:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:18:52 INFO - Installing collected packages: blobuploader, requests, docopt 17:18:52 INFO - Running setup.py install for blobuploader 17:18:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:18:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:18:52 INFO - Running setup.py install for requests 17:18:53 INFO - Running setup.py install for docopt 17:18:53 INFO - Successfully installed blobuploader requests docopt 17:18:53 INFO - Cleaning up... 17:18:53 INFO - Return code: 0 17:18:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:53 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')]} 17:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:53 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')]} 17:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:53 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 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:18:53 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 17:18:53 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 17:18:53 INFO - Using env: {'DISPLAY': ':0', 17:18:53 INFO - 'HOME': '/home/cltbld', 17:18:53 INFO - 'LANG': 'en_US.UTF-8', 17:18:53 INFO - 'LANGUAGE': 'en_US:en', 17:18:53 INFO - 'LOGNAME': 'cltbld', 17:18:53 INFO - 'MAIL': '/var/mail/cltbld', 17:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:53 INFO - 'MOZ_NO_REMOTE': '1', 17:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:53 INFO - 'NO_EM_RESTART': '1', 17:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:53 INFO - 'PWD': '/builds/slave/test', 17:18:53 INFO - 'SHELL': '/bin/bash', 17:18:53 INFO - 'SHLVL': '1', 17:18:53 INFO - 'TERM': 'linux', 17:18:53 INFO - 'TMOUT': '86400', 17:18:53 INFO - 'USER': 'cltbld', 17:18:53 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:53 INFO - '_': '/tools/buildbot/bin/python'} 17:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:18:53 INFO - Running setup.py (path:/tmp/pip-1VQ4TB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:18:53 INFO - Running setup.py (path:/tmp/pip-3jYuee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:18:53 INFO - Running setup.py (path:/tmp/pip-xQB3lK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:18:53 INFO - Running setup.py (path:/tmp/pip-jKS_EL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:18:53 INFO - Running setup.py (path:/tmp/pip-oPy4Tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:18:54 INFO - Running setup.py (path:/tmp/pip-vD8HAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:18:54 INFO - Running setup.py (path:/tmp/pip-fCleIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:18:54 INFO - Running setup.py (path:/tmp/pip-HQGhfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:18:54 INFO - Running setup.py (path:/tmp/pip-HyvuKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:18:54 INFO - Running setup.py (path:/tmp/pip-qIdU6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:18:54 INFO - Running setup.py (path:/tmp/pip-tqEgwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:18:54 INFO - Running setup.py (path:/tmp/pip-TjGt6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:18:54 INFO - Running setup.py (path:/tmp/pip-XzRc32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:18:54 INFO - Running setup.py (path:/tmp/pip-0AesIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:54 INFO - Running setup.py (path:/tmp/pip-oc090U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:18:54 INFO - Running setup.py (path:/tmp/pip-qkMcYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:18:54 INFO - Running setup.py (path:/tmp/pip-J4ATtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:18:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:18:54 INFO - Running setup.py install for manifestparser 17:18:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Running setup.py install for mozcrash 17:18:55 INFO - Running setup.py install for mozdebug 17:18:55 INFO - Running setup.py install for mozdevice 17:18:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Running setup.py install for mozfile 17:18:55 INFO - Running setup.py install for mozhttpd 17:18:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Running setup.py install for mozinfo 17:18:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Running setup.py install for mozInstall 17:18:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Running setup.py install for mozleak 17:18:55 INFO - Running setup.py install for mozlog 17:18:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:18:55 INFO - Running setup.py install for moznetwork 17:18:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:18:56 INFO - Running setup.py install for mozprocess 17:18:56 INFO - Running setup.py install for mozprofile 17:18:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:18:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:18:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:18:56 INFO - Running setup.py install for mozrunner 17:18:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:18:56 INFO - Running setup.py install for mozscreenshot 17:18:56 INFO - Running setup.py install for moztest 17:18:56 INFO - Running setup.py install for mozversion 17:18:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:18:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:18:56 INFO - Cleaning up... 17:18:56 INFO - Return code: 0 17:18:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:56 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')]} 17:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:56 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')]} 17:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:56 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 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:18:56 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 17:18:56 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 17:18:56 INFO - Using env: {'DISPLAY': ':0', 17:18:56 INFO - 'HOME': '/home/cltbld', 17:18:56 INFO - 'LANG': 'en_US.UTF-8', 17:18:56 INFO - 'LANGUAGE': 'en_US:en', 17:18:56 INFO - 'LOGNAME': 'cltbld', 17:18:56 INFO - 'MAIL': '/var/mail/cltbld', 17:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:56 INFO - 'MOZ_NO_REMOTE': '1', 17:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:18:56 INFO - 'NO_EM_RESTART': '1', 17:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:56 INFO - 'PWD': '/builds/slave/test', 17:18:56 INFO - 'SHELL': '/bin/bash', 17:18:56 INFO - 'SHLVL': '1', 17:18:56 INFO - 'TERM': 'linux', 17:18:56 INFO - 'TMOUT': '86400', 17:18:56 INFO - 'USER': 'cltbld', 17:18:56 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:56 INFO - '_': '/tools/buildbot/bin/python'} 17:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:18:56 INFO - Running setup.py (path:/tmp/pip-diNm_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:18:57 INFO - Running setup.py (path:/tmp/pip-g9mFcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:18:57 INFO - Running setup.py (path:/tmp/pip-cm5_x3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:18:57 INFO - Running setup.py (path:/tmp/pip-nS8i16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:18:57 INFO - Running setup.py (path:/tmp/pip-hYoLlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:18:57 INFO - Running setup.py (path:/tmp/pip-PlNi8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:18:57 INFO - Running setup.py (path:/tmp/pip-AtZrhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:18:57 INFO - Running setup.py (path:/tmp/pip-DkPy2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:18:57 INFO - Running setup.py (path:/tmp/pip-kkP4ZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:18:57 INFO - Running setup.py (path:/tmp/pip-0M5Ut1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:18:57 INFO - Running setup.py (path:/tmp/pip-oBU4TF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:18:57 INFO - Running setup.py (path:/tmp/pip-WAwZen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:18:57 INFO - Running setup.py (path:/tmp/pip-536WnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:18:57 INFO - Running setup.py (path:/tmp/pip-axeKDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:18:57 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)) 17:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:57 INFO - Running setup.py (path:/tmp/pip-3TWDCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:58 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)) 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:18:58 INFO - Running setup.py (path:/tmp/pip-HeQkTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:18:58 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)) 17:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:18:58 INFO - Running setup.py (path:/tmp/pip-OQMwGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:18:58 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)) 17:18:58 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)) 17:18:58 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)) 17:18:58 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)) 17:18:58 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)) 17:18:58 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)) 17:18:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:18:58 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 17:18:58 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 17:18:58 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 17:18:58 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 17:18:58 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 17:18:58 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 17:19:00 INFO - Downloading blessings-1.6.tar.gz 17:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:19:00 INFO - Installing collected packages: blessings 17:19:00 INFO - Running setup.py install for blessings 17:19:00 INFO - Successfully installed blessings 17:19:00 INFO - Cleaning up... 17:19:00 INFO - Return code: 0 17:19:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:19:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:19:00 INFO - Reading from file tmpfile_stdout 17:19:00 INFO - Current package versions: 17:19:00 INFO - argparse == 1.2.1 17:19:00 INFO - blessings == 1.6 17:19:00 INFO - blobuploader == 1.2.4 17:19:00 INFO - docopt == 0.6.1 17:19:00 INFO - functools32 == 3.2.3-2 17:19:00 INFO - jsonschema == 2.5.1 17:19:00 INFO - manifestparser == 1.1 17:19:00 INFO - mozInstall == 1.12 17:19:00 INFO - mozcrash == 0.17 17:19:00 INFO - mozdebug == 0.1 17:19:00 INFO - mozdevice == 0.48 17:19:00 INFO - mozfile == 1.2 17:19:00 INFO - mozhttpd == 0.7 17:19:00 INFO - mozinfo == 0.9 17:19:00 INFO - mozleak == 0.1 17:19:00 INFO - mozlog == 3.3 17:19:00 INFO - moznetwork == 0.27 17:19:00 INFO - mozprocess == 0.23 17:19:00 INFO - mozprofile == 0.28 17:19:00 INFO - mozrunner == 6.12 17:19:00 INFO - mozscreenshot == 0.1 17:19:00 INFO - mozsystemmonitor == 0.3 17:19:00 INFO - moztest == 0.7 17:19:00 INFO - mozversion == 1.4 17:19:00 INFO - psutil == 3.1.1 17:19:00 INFO - requests == 1.2.3 17:19:00 INFO - wsgiref == 0.1.2 17:19:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:19:00 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')]} 17:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:00 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')]} 17:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:00 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 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:19:00 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 17:19:00 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 17:19:00 INFO - Using env: {'DISPLAY': ':0', 17:19:00 INFO - 'HOME': '/home/cltbld', 17:19:00 INFO - 'LANG': 'en_US.UTF-8', 17:19:00 INFO - 'LANGUAGE': 'en_US:en', 17:19:00 INFO - 'LOGNAME': 'cltbld', 17:19:00 INFO - 'MAIL': '/var/mail/cltbld', 17:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:00 INFO - 'MOZ_NO_REMOTE': '1', 17:19:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:00 INFO - 'NO_EM_RESTART': '1', 17:19:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:00 INFO - 'PWD': '/builds/slave/test', 17:19:00 INFO - 'SHELL': '/bin/bash', 17:19:00 INFO - 'SHLVL': '1', 17:19:00 INFO - 'TERM': 'linux', 17:19:00 INFO - 'TMOUT': '86400', 17:19:00 INFO - 'USER': 'cltbld', 17:19:00 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:00 INFO - '_': '/tools/buildbot/bin/python'} 17:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:00 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)) 17:19:00 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)) 17:19:00 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)) 17:19:00 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)) 17:19:00 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)) 17:19:00 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)) 17:19:00 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)) 17:19:00 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)) 17:19:00 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)) 17:19:00 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)) 17:19:00 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)) 17:19:00 INFO - Cleaning up... 17:19:00 INFO - Return code: 0 17:19:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:19:00 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')]} 17:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:00 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')]} 17:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:00 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 0x1693030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1954180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, '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': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', '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 17:19:00 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 17:19:00 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 17:19:00 INFO - Using env: {'DISPLAY': ':0', 17:19:00 INFO - 'HOME': '/home/cltbld', 17:19:00 INFO - 'LANG': 'en_US.UTF-8', 17:19:00 INFO - 'LANGUAGE': 'en_US:en', 17:19:00 INFO - 'LOGNAME': 'cltbld', 17:19:00 INFO - 'MAIL': '/var/mail/cltbld', 17:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:00 INFO - 'MOZ_NO_REMOTE': '1', 17:19:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:00 INFO - 'NO_EM_RESTART': '1', 17:19:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:00 INFO - 'PWD': '/builds/slave/test', 17:19:00 INFO - 'SHELL': '/bin/bash', 17:19:00 INFO - 'SHLVL': '1', 17:19:00 INFO - 'TERM': 'linux', 17:19:00 INFO - 'TMOUT': '86400', 17:19:00 INFO - 'USER': 'cltbld', 17:19:00 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:00 INFO - '_': '/tools/buildbot/bin/python'} 17:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:19:01 INFO - Cleaning up... 17:19:01 INFO - Return code: 0 17:19:01 INFO - Running post-action listener: _resource_record_post_action 17:19:01 INFO - Running post-action listener: _start_resource_monitoring 17:19:01 INFO - Starting resource monitoring. 17:19:01 INFO - [mozharness: 2016-10-19 00:19:01.041811Z] Finished create-virtualenv step (success) 17:19:01 INFO - [mozharness: 2016-10-19 00:19:01.042242Z] Running install step. 17:19:01 INFO - Running pre-action listener: _resource_record_pre_action 17:19:01 INFO - Running main action method: install 17:19:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:19:01 INFO - Reading from file tmpfile_stdout 17:19:01 INFO - Detecting whether we're running mozinstall >=1.0... 17:19:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:19:01 INFO - Reading from file tmpfile_stdout 17:19:01 INFO - Output received: 17:19:01 INFO - Usage: mozinstall [options] installer 17:19:01 INFO - Options: 17:19:01 INFO - -h, --help show this help message and exit 17:19:01 INFO - -d DEST, --destination=DEST 17:19:01 INFO - Directory to install application into. [default: 17:19:01 INFO - "/builds/slave/test"] 17:19:01 INFO - --app=APP Application being installed. [default: firefox] 17:19:01 INFO - mkdir: /builds/slave/test/build/application 17:19:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:19:14 INFO - Reading from file tmpfile_stdout 17:19:14 INFO - Output received: 17:19:14 INFO - /builds/slave/test/build/application/firefox/firefox 17:19:14 INFO - Running post-action listener: _resource_record_post_action 17:19:14 INFO - [mozharness: 2016-10-19 00:19:14.855343Z] Finished install step (success) 17:19:14 INFO - [mozharness: 2016-10-19 00:19:14.855624Z] Running run-tests step. 17:19:14 INFO - Running pre-action listener: _resource_record_pre_action 17:19:14 INFO - Running main action method: run_tests 17:19:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:19:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:19:14 INFO - Python 2.7.3 17:19:14 INFO - Return code: 0 17:19:14 INFO - grabbing minidump binary from tooltool 17:19:14 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')]} 17:19:14 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 0x19523b0>, '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 0x19532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1953740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:19:14 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 17:19:14 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 17:19:14 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 17:19:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:19:14 INFO - Return code: 0 17:19:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:19:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:19:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:19:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:19:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:19:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 17:19:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 17:19:14 INFO - Using env: {'DISPLAY': ':0', 17:19:14 INFO - 'HOME': '/home/cltbld', 17:19:14 INFO - 'LANG': 'en_US.UTF-8', 17:19:14 INFO - 'LANGUAGE': 'en_US:en', 17:19:14 INFO - 'LOGNAME': 'cltbld', 17:19:14 INFO - 'MAIL': '/var/mail/cltbld', 17:19:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:19:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:14 INFO - 'MOZ_NO_REMOTE': '1', 17:19:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:19:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:14 INFO - 'NO_EM_RESTART': '1', 17:19:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:14 INFO - 'PWD': '/builds/slave/test', 17:19:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:19:14 INFO - 'SHELL': '/bin/bash', 17:19:14 INFO - 'SHLVL': '1', 17:19:14 INFO - 'TERM': 'linux', 17:19:14 INFO - 'TMOUT': '86400', 17:19:14 INFO - 'USER': 'cltbld', 17:19:14 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963', 17:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:14 INFO - '_': '/tools/buildbot/bin/python'} 17:19:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 17:19:15 INFO - mozversion application_buildid: 20161018141823 17:19:15 INFO - mozversion application_changeset: 11ba9af07367fa76691961289d5e46d880a868e8 17:19:15 INFO - mozversion application_display_name: Firefox 17:19:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:19:15 INFO - mozversion application_name: Firefox 17:19:15 INFO - mozversion application_remotingname: firefox 17:19:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:19:15 INFO - mozversion application_vendor: Mozilla 17:19:15 INFO - mozversion application_version: 50.0 17:19:15 INFO - mozversion platform_buildid: 20161018141823 17:19:15 INFO - mozversion platform_changeset: 11ba9af07367fa76691961289d5e46d880a868e8 17:19:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:19:15 INFO - mozversion platform_version: 50.0 17:19:15 INFO - using testdate: 1476836355 17:19:15 INFO - actual date: 1476836355 17:19:15 INFO - starting webserver on 'localhost:47388' 17:19:15 INFO - SUITE-START | Running 2 tests 17:19:15 INFO - TEST-START | dromaeo_css 17:19:15 INFO - operating with platform_type : linux_ 17:19:15 INFO - Initialising browser for dromaeo_css test... 17:19:15 INFO - TEST-INFO | started process 19579 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyjmQA1/profile http://localhost:47388/getInfo.html) 17:19:19 INFO - PROCESS | 19579 | __metrics Screen width/height:1600/1200 17:19:19 INFO - PROCESS | 19579 | colorDepth:24 17:19:19 INFO - PROCESS | 19579 | Browser inner width/height: 1024/697 17:19:19 INFO - PROCESS | 19579 | __metrics 17:19:19 INFO - PROCESS | 19579 | 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" 17:19:19 INFO - PROCESS | 19579 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:19:20 INFO - TEST-INFO | 19579: exit 0 17:19:20 INFO - Browser initialized. 17:19:20 INFO - Running cycle 1/1 for dromaeo_css test... 17:19:20 INFO - TEST-INFO | started process 19667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyjmQA1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:19:32 INFO - PROCESS | 19667 | RSS: Main: 143470592 17:19:32 INFO - PROCESS | 19667 | 17:20:23 INFO - PROCESS | 19667 | 1476836423766 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] 17:22:32 INFO - PROCESS | 19667 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47388/tests/dromaeo/cssquery-ext.html) 17:22:33 INFO - PROCESS | 19667 | RSS: Main: 271433728 17:22:33 INFO - PROCESS | 19667 | 17:25:36 INFO - PROCESS | 19667 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-ext.html (next: http://localhost:47388/tests/dromaeo/cssquery-jquery.html) 17:25:36 INFO - PROCESS | 19667 | RSS: Main: 228634624 17:25:36 INFO - PROCESS | 19667 | 17:28:38 INFO - PROCESS | 19667 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47388/tests/dromaeo/cssquery-mootools.html) 17:28:39 INFO - PROCESS | 19667 | RSS: Main: 177676288 17:28:39 INFO - PROCESS | 19667 | 17:31:41 INFO - PROCESS | 19667 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47388/tests/dromaeo/cssquery-prototype.html) 17:31:41 INFO - PROCESS | 19667 | RSS: Main: 176062464 17:31:41 INFO - PROCESS | 19667 | 17:34:44 INFO - PROCESS | 19667 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47388/tests/dromaeo/cssquery-yui.html) 17:34:44 INFO - PROCESS | 19667 | RSS: Main: 176795648 17:34:44 INFO - PROCESS | 19667 | 17:37:47 INFO - PROCESS | 19667 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-yui.html (next: http://localhost:47388/tests/dromaeo/cssquery-dojo.html) 17:37:47 INFO - PROCESS | 19667 | RSS: Main: 259481600 17:37:47 INFO - PROCESS | 19667 | 17:37:47 INFO - PROCESS | 19667 | __start_tp_report 17:37:47 INFO - PROCESS | 19667 | _x_x_mozilla_page_load 17:37:47 INFO - PROCESS | 19667 | _x_x_mozilla_page_load_details 17:37:47 INFO - PROCESS | 19667 | |i|pagename|runs| 17:37:47 INFO - PROCESS | 19667 | |0;dojo.html;3234.288456539561;3247.8957154567443;3253.3168034712694;3272.296456261903;3291.7366610671147;5614.522765564927;5754.338251101125;5762.106873434619;5780.653160810344;5816.040353341694;5815.360310365867;5824.242848429709;5847.883042339161;5849.85375365615;5855.47300742933;5263.526282634562;5307.893842123155;5329.067413202693;5359.115745901921;5369.785208591652;5101.38783345999;5159.355080614923;5344.438833922441;5352.5182733553975;5358.866028349303;5148.8712782180555;5186.196139598368;5193.298904647881;5235.476452354772;5261.079311120558;5212.50481204285;5322.281491998569;5359.946400535984;5360.705161216131;5362.758675859557;5198.740062996872;5248.239005344204;5302.072137375943;5318.574514038867;5323.707196104213;5557.166425036236;5647.265855438808;5655.830325090254;5681.460261275172;5808.157817116495;6165.106059621375;6177.598456100339;6196;6219.906701399483;6222.751089956397;5934.050551911673;6213.596116252429;6222.066689996492;6225.470834979033;6226.968865155691;5636.210930469738;5655.1517272409055;5661.575381846378;5671.2060311556415;5672.716364181816;6215;6222.315545290015;6223.471004964629;6227.252729672377;6326.905096423558;5834.67909264986;6007.669578173155;6051.667158306335;6066;6068.939310606926;6186.443220110212;6226.25284965807;6226.875462490725;6226.968865155736;6228.283747369111;6080.8783824324155;6082.57421980466;6089.604175728564;6091.756329746849;6096.512279017668;5933.851653708605;5985.40145985398;6056.909146362808;6081.452669259699;6097.17844926735;4518.254488009442;4554.886127846764;4566.74882881438;4569.95430045702;4585.495595484561;4027.4160246764054;4056.0468289951837;4075.8777236682945;4076.9796151019054;4092.6930480214105;5698.145278208219;5748;5758.568107391962;5772.422757724194;5779.393163717833;6125.907024958145;6233.844153896189;6244;6244.375562443719;6272.466840318532;5872.17789509469;5877.206577112035;5881;5884.7940322088525;5893.5579831512805;5048.570871475892;5106.846794596168;5214.113600687816;5219.347581552306;5227.2420499028185;6332.271788744353;6471.741130354733;6487.6756162191705;6493.25327587324;6494.6103233806125;6186.628802271878;6205.2553693557065;6211.192544969125;6217.533684973555;6219.844503887439;6114.724837382418;6208.689565521616;6469.935300646934;6483.157189565326;6495.220573531206;5973.611715238496;6024.487918526799;6034.064719968412;6056.454919057306;6095.725692343766;6109.84725381869;6118.449339559461;6125;6128.050152226412;6128.325884152829;5737.799177028784;5991.520678345809;6010.4891084258325;6013.819585412444;6019.72911219005;5992.700364981646;6106.542009349228;6108.4807791338235;6119.296280927751;6145;6014.72933717975;6072.423119803611;6079.0273556230795;6088.482478989158;6099.634021958696;5901.704914754331;5945.137954996588;6033.577649564489;6104.725287362168;6124.356942520971;5823.912641310469;5913.231279933753;5918;5941.7623295067715;5953.1070339449425 17:37:47 INFO - PROCESS | 19667 | |1;ext.html;559.504838218177;617.6016469377251;651.1892693596471;686.0566720758957;691.8616276744652;35216.11959701009;45442.545574544245;46350.536494635046;46458;46940.53059469404;49684.254736178984;50207.24689129666;50551.74724126383;50598.74700626501;50600.24099638508;8580.39937204396;9358.4384936904;9515.00092490289;9520.761980950463;9522.238220942343;11007.06439952605;11059.281146725478;11280.548778048867;11624.07007439407;11638.301701897872;11471.483783229733;11549.6535103947;11571.594994175206;11598.826017609743;11600.651980440558;11255.66233013011;11316.151288653302;11334.829977550344;11339.546418143264;11340.659780206606;11189.720256993558;11265;11274.943625281903;11276.041536469409;11278.774424511465;46134;46360.304595431095;46539.767301163614;46701;46712.53287467116;3902.3366027775346;3978.42312864633;4076.5515793262716;4117.794110294473;4583.633309335245;35167.29665406678;35581.28837423237;35636.821815890755;36041.81979090114;37569;4580.725156490621;4631.0506346198945;4655.976720116411;4702.130105930414;4812.133815913135;359497;361353;361742;362288;362493;64207.39481512999;64658;64681.35318646848;64747;64794;40080;41030.38454423186;41369.58630413718;43695.781521092824;43707.56292437035;16388.83611163873;16589.087600182105;16594;16597.83402165987;16606.667866642598;10781.838272425919;10898.291611045257;12206.877931220752;12900.80648790269;13033.15284506523;21228.150873964867;21270.893645531673;21324;21436.571268574542;21492.892535537536;7262.854742905112;7344.25576415515;7397.741079062207;7436.191998401657;7632;1021.6015753856002;1036.5439206749156;1119.2501024313729;1249.9750603539455;1260.1682889293022;35384.46923296153;35645.82177089098;35755.28489430249;35842;35847.28305433877;1176.5529098942347;1193.7493126443371;1193.9641810745518;1199.3763243113533;1229.8032314829763;14914.328855201325;18550.907245463957;19153.808461915203;19682.212711491666;19974.50063748418;602265;608880;608934;609914;610138;64405.67797160984;64428.67785661042;64438.355616444176;64446.67776661087;64853.67573162198;1325.3969443903002;1427.5717284814514;1436.892233082544;1439.2579293835943;1482.770170623555;33835.323293534486;36770.63229367672;37031.814840925625;37201.25597488089;37289.881303560935;55465.44534554603;55623.72188139033;56237;56269.15596266141;56304.43695562992;431105;431735;432525;433648;433870;27271.454570908867;30256.243593909447;30530.389392212477;31082.689173108884;31306.21734456657;52208;53966.730166350484;54154;54168.45831541634;59358;46198.769006154755;46454.30318545292;46653.76673116613;46949;48007.279890800964;44331.33502997393;46593.76703116463;46764.53235467738;46911;47040;45198.32202517031;45765.77117114393;46018.76990615026;46039.53960460486;46759.53240467688;36719.63280367269;40553.797231013654;40859;41260;41786.95532611709;30306;30744.692553074183;30761.538576920917;31077.378452430374;31108.688913110578 17:37:47 INFO - PROCESS | 19667 | |2;jquery.html;418.10942692065896;420.1171088569031;438.4519350811486;438.75649014796784;450.31102413307633;19545.902270488645;21183.894080529593;21203.469913252156;21444.571108577857;21756.782432175674;19769;20018.399448016546;20548.280810171647;20718;20742.585148297025;5844.795432159875;6123.540734444912;6130.325664176937;6133.601315914473;6134.110553969672;5864.091065884794;5917.100770170248;5995.490383317423;6024.21685180928;6043.214382130339;5984.431479009487;5987.161797348353;5988.3712210217955;5997.190379298804;5999.340072592011;5818.301803783531;6030.216071910668;6062.605930614496;6147.569670123094;6151.784687535915;5932.080527518242;5940.168376427304;5980.910286345708;5981.9401805981815;6001.1898393717165;15015.549533514011;15285.235738213156;15285.465008724752;15353.232338383148;15359.539213823604;2314.456102815837;2390.5099454611764;2425.6604075429454;2512.9120480783085;2888.3501212227293;11207.551697932073;11366.488508017159;14697.77953330701;15454.690906181813;16040;1981.3362523554626;1998.3105828489292;2038.8063134002393;2076.8442366822396;2099.3374184227537;63677;63984.313880828755;77303;84977.99999999939;90081.54959225227;11587.246828956091;12024.33866137371;12109.818352724717;12215.633530994084;12266.325352105541;17213.913930430266;17226.569335766457;17251.74122388165;17265.827341726672;17271.13644317779;8534.231919127309;8552.828943421096;8663.306935445275;8740;8740.388172827841;7919.3268572170855;7968.880466793003;8114.959425202836;8162.551059691774;8216.424850260544;5144.305518754995;5194.97402512985;5209.192575150858;5226.97386513065;5308.654937429132;1961.6370971370343;1975.170428420067;2003.3889663652435;2078.8856612886134;2183.3559100065518;926.893407258174;1035.5236591168148;1041.6979076067855;1057.8730552333768;1074.661481633283;20347.69478457824;20681.89659051695;20862.478438039172;20878.686819697716;20939.37181884546;1031.6079889641887;1042.3329069395593;1061.5912873543587;1070.7483741320784;1078.926468164166;10753.408562529135;11722.413879306172;11725.882741172649;11758.118141139448;11807.114466415052;501101;511773;512856;533659;544103;78964;81884;85534;87811.56094219488;97886;986.8470387090013;989.450854775592;1020.0819262663514;1020.4183615339185;1030.175859312547;18595.907020464812;18973.525661858013;18983.620327593646;19052;19349.322773702854;31566.684333157293;42919.356209657504;43874.78062609667;43944.56055439405;44818;58776.70611646914;59163.70418147882;60220.69889650699;60581;61113.69443152934;8420.789480263042;8489.787755306166;8632.309415246891;8711;8719.956400217958;11836.408179590813;13627.454901803816;13672.86327136716;13735.656608584864;14201.573952781433;12195.695107622381;12358.938205308916;12365.258084514959;12428;12747.298898560668;5692.032354499828;9433.952830235805;11902.940485297519;12055.221465246867;12205.145639805129;12108.939455302667;12292.323922184365;12387.19483233587;12628.684282893002;12807.61577152687;8479.872801908099;8558.786030349042;8587.742367728979;8773.824523509367;8920.286377089948;10632.362058276529;12311.876881231074;12365.876341236473;12386.380680965736;12388.56640017595 17:37:47 INFO - PROCESS | 19667 | |3;mootools.html;1574.0948954351247;1585.9920700396497;1799.6670615936052;1875.7373967644523;1901.0969789350052;9012.459252444849;9566.09122133398;9717.854232186504;9718.514074296274;9726.416415015105;8516.61675224615;8801.471911685287;8802.559872006406;8905.15401036902;9333.53332333384;2441.3896525868577;2536.8985240590355;2539.669842920427;2559.001989224204;2561.078011915709;2509.912153074643;2542.6313184588225;2551.374913146282;2556.463142740027;2567.833090849098;2470.4441500662388;2473.208573256559;2480.6651102101164;2483.5777917754026;2516.6032851767263;2418.806495480357;2429.842060266077;2430.3802530354646;2433.537627850703;2447.229122826313;2463.3841539615096;2464.1621848571394;2468.025130073629;2468.123816045296;2468.90124395024;9512.95243523778;9577.712668619952;9584.137427631547;9585.952070239673;9587.42475451468;5626.634268772516;5709.200711900337;5713.371529131792;5730.570207234432;5894.440028197314;3491.389006923778;3560.43033114703;3702.3705969985167;3716.4982727331735;3725.5715592706642;1197.0603076584925;1203.7592481503648;1230.4278510492575;1238.7708273969254;2137.700721898935;13213.33933303331;13800;13844.238566878817;13856.030077894551;13879.167249965034;4931.038447502774;4966.90066198674;5045.31888195089;5095.031943930641;5117.718525481134;11042.116630669527;11052.502637381338;11053.834192487118;11058.060064894413;11059.557617695202;5824.271966004249;5853.063509838491;5863.912041319299;5866.794662186889;5870.9412905870395;5207.297014903015;5254.632175747737;5280.1815718563685;5453.481919217747;5461.1808228766;3998.8400463981698;4079.97960010204;4126.504819421689;4144.357624568197;4188.643965262987;1175.0951767139425;1175.6179241746468;1194.713268815478;1207.9154459187948;1226.47874653272;935.3919952031234;973.732223638505;1007.9596816127273;1080.1844607321423;1103.3765922253901;6709.496787740842;6833.384995350442;6856.211535673362;6861.656917154122;6869.450443964471;1048.3028785857473;1091.170710260208;1101.7961677089763;1112.5827814569536;1163.6334554615269;4412.007298357844;4438.7114837535455;4644.419447569054;4681.648876334289;4734.07685501324;11264.436778161224;12005.519779208735;12122.212056216318;12414.441350139265;12502.999760019358;12201.633950981484;12400.379981000913;12402.069844761498;12416.255024698366;12520.123591348518;9088.636454541744;9099.408538444979;9404.294677899048;9405.43567385959;9409.764755881159;6659.866802664016;6779.288174741779;6805.591664499948;6846.863062738617;6931.757388491378;10919.962603552649;11011.559537618405;11077.33535987811;11118.166137539878;11196.328220306808;9713.757156070873;10994.560217591206;11378.08975281959;11488.483018264365;11517.193796434169;8636.309095272489;8956.68651597191;8963.686270980485;8969.237614802814;8989.146031127033;9632.566534505824;9634.13292803651;9637.951810240904;9738.513074346112;9759.316847820868;8705.52119633401;8755.124487551195;8813.603387847434;8861.778455538662;9032.187103160748;8418.368622353226;8602.354823388396;8604.913950860411;8700.564971751513;8881.156290152425;7572.432067594842;7697.498987697345;7830.765077047698;7883.054033516015;7979.800504987422;5815.389384114607;5847.707614619167;5857.531397488276;5892.882142357214;6150.231221097363;5958.582899197015;6111.816645500642;6159.5072394209255;6178.845528861634;6200.782972595937 17:37:47 INFO - PROCESS | 19667 | |4;prototype.html;344.0744397570535;345.95329630499884;361.91856832212756;361.9800910949897;365.84085922623643;5592.5805564582615;5602.24369710089;5635.28123922204;5658.688772117537;5678.558219965414;5548.140038294061;5600.355959064709;5620.803271885485;5636.985342638323;5640.830775076744;3878.224355128971;4117.608827161415;4351.064521127957;4361.433013708213;4364.890877728067;4363.236433624119;4363.410939523172;4364.105358401536;4379.890502737425;4408.118376324732;4376.365427013097;4380.956190438103;4400.581944715247;4427.71219870709;4440.600345968862;4314.546972567882;4353.651707863363;4397.516273209944;4408.20652282589;4415.403920470743;3841.9615803841884;3853.5953724859046;3863.884083477472;3866.516685414323;3874.8062596870045;5407.864348486823;5409.972950135263;5410.729463526808;5423.945760542383;5502.642328248651;4844.249141383091;4976.4525902150735;5014.59883209346;5020.7740651670765;5052.671576347562;5774.884502309972;5780.913286300709;5781.207825574085;5789.594728369016;5816.505597024258;4268.423762792015;4298.688899885534;4302.333138363559;4306.160298741746;4306.827726890952;5970.34326224115;6008.879822403527;6009.969950150265;6077.270727512682;6159.784407545759;4319.682496838432;4327.37253098299;4344;4374.278244089754;4379.540148284448;4442.888927776831;4443.600075993111;4445.3998710174355;4446.444194475691;4453.821847126143;4921.286413470027;4929.2359684248995;4935.827246046371;5015.774290156952;5038.974805126024;5774.6718254801635;5775.191473193756;5777.826665200051;5826.475617194473;5835.67903765297;1509.5471358592597;1550.71311807316;1551.604340893065;1552.5251011538858;1556.0819116720963;997.7106639074732;1007.7480629842539;1011.5245834457793;1013.2653825976108;1029.4543891737392;4902.656814022984;4933.28467372229;4936.6297527685565;4939.333190019373;4942.604591632661;5168.415792103948;5180.4560521145495;5228.163493840967;5230.215467679878;5265.025260727677;4586.541345865387;4891.779869905863;4913.656044076952;4934.963657631865;4948.931662369282;2785.916422507328;2786.679531853863;2788.525950588405;2791.972080279171;2796.3428594280326;5232.895342093137;5263.921041184385;5310.601704872072;5354.196870469461;5384.757685904144;5073.036123136666;5300.655457395257;5305.904167853676;5328.907573947409;5843.707814609253;6064.848378790477;6135.190118914931;6188.566800323934;6191.226096737908;6198.659073750897;5940.465358117617;5951;5966.284045914518;5987.910181347196;6014.6391216527145;5802.622829516068;5866.6186697864505;5875.00249915031;5911.172435858897;5914.408559144051;6176.81469555914;6182.598131121463;6183.3816618337805;6193.349698281616;6243.283097148321;4142.798588409328;4420;4448.399466072168;4454.28731402974;4459.420275364311;5952.970235148797;5979.222701048836;5990.820275391745;5995.070764031582;6006.939930600638;5799.739011744397;5829.562782791223;5870.530357571299;5890.705464726832;5951.642901425928;5653.953674121373;5845.561582881216;5851.853703657443;5854.414558544282;5870.503021729432;5785.508231800344;5800.7099645018425;5831.212786273799;5834.329052158886;5857.297124344936;5681.857953551194;5900.409959004065;5940.227770389822;5946.73239704223;6008.699565021819;5665.886682266414;5737.971310143423;5740.885182296414;5756.539476841926;5761.740721667451 17:37:47 INFO - PROCESS | 19667 | |5;yui.html;809.522381794741;812.4475356757403;848.9023762267337;888.4402826219483;890.2299510923053;15526.456574019912;16411.015339079648;16441.26014329355;16444.83555164448;16460.588485287873;15242.695146097072;15280.235988200573;15294.158821264826;15304.84695153048;15308.46420375287;741.7737590035038;872.402404353017;887.702619622424;890.6437425029975;894.4990805149138;886.4636894678723;889.888763904512;890;890.2121622364222;890.3989806880362;946.8958414574391;947.275334369208;947.3368641950627;947.663579429303;947.8625599288133;937.9249660027182;938.3290946972879;939.065629698451;939.4175611121115;939.6899023322321;695.8538706871564;696.5472442912097;699.9055127557765;700.0444393403019;701.6772284749021;16045;16096.678066438722;16158.75761863573;16165.757513637305;16180.514584562365;16621.6675666486;16658.0838053907;16938.491845244538;17695.29218831245;17757.02336371499;12803.935980320131;12916.289604071771;13003.349832508431;13107.606771796862;13137.474501019948;4037.4751282333405;4113.691473139526;4117.958820411817;4163.10493243954;4170;86261;86326.5683671584;89001.55499222463;89026;89485;11418.371989540488;11680.70798230051;11868.953582355232;12640.936795315965;12703.364831758376;25346.873265633552;25365.112221072173;25384;25404.618930716053;25426.872865635552;9024.909750902538;9038.63845446188;9157.633694652139;9280.628774849065;9502.714918552454;4969.552740253394;5197.532222099953;5202.76587553561;5332.600054995891;5350.732463376816;3906.7069969752383;4014.116894283253;4047.494063242095;4054;4063.0858056936954;698.2284575544053;712.7968528969323;715.1775458223085;734.5629350536423;741.1143683298407;643.8326035230874;770.4414299632722;787.4566549081115;789.7157023471545;802.3460879383284;10182.643607473703;10277.94861025705;10353.378797272188;10360.533775980099;10394.324368915995;740.6185814305637;754.3550264523753;773.6905237904883;788.1960400391569;798.3134504326275;4877.6829506081995;4972.353594032753;4977.651564390458;5139.1263485206855;5196.870078248074;119815;133980;135743;141388;144065.27967360095;61576;64208.35791642024;105742;108877;109474;89879.15785473923;91993.54003229941;92097.53951230201;94186.5290673556;96681.51659241659;10372.377657340583;10405.843912341472;11085.113190944867;11206.551737930391;11219.55121795119;53700.46299537111;53976.19035714389;54286.72856635691;54407.45592544024;55302;75432.62283688546;79832.60083699544;80250;82958.58520707599;85827.14172858192;12628.873711263137;12914.160579562298;13357.53248636293;13821.792673110112;14331.570052898429;14027.158370497802;14056.508022219174;14176.858231417555;14451.638709032359;14518.491852785102;13191.472341106248;13308.600741977756;14746.11523308605;14945;15514.689706206038;14551.636209094857;14729.263536822902;15177.24113794328;15317.693646126792;16619.41832035873;14976.775348369565;15006.624834379229;15737.606559835638;16076.919615402316;16104.27530761142;11199.552017919192;11214.66356009321;11232.66302010941;11987.64037078889;12019.399030048637;11184.88815111871;11545;12700.936495317834;12992.198691296713;13152.408141633457 17:37:47 INFO - PROCESS | 19667 | __end_tp_report 17:37:47 INFO - PROCESS | 19667 | __start_cc_report 17:37:47 INFO - PROCESS | 19667 | _x_x_mozilla_cycle_collect,1083 17:37:47 INFO - PROCESS | 19667 | __end_cc_report 17:37:47 INFO - PROCESS | 19667 | __startTimestamp1476837467585__endTimestamp 17:37:47 INFO - PROCESS | 19667 | 17:37:47 INFO - PROCESS | 19667 | ------- Summary: start ------- 17:37:47 INFO - PROCESS | 19667 | Number of tests: 6 17:37:47 INFO - PROCESS | 19667 | 17:37:47 INFO - PROCESS | 19667 | [#0] dojo.html Cycles:165 Average:5708.83 Median:5934.05 stddev:676.31 (11.4%) stddev-sans-first:649.93 17:37:47 INFO - PROCESS | 19667 | Values: 3234.3 3247.9 3253.3 3272.3 3291.7 5614.5 5754.3 5762.1 5780.7 5816.0 5815.4 5824.2 5847.9 5849.9 5855.5 5263.5 5307.9 5329.1 5359.1 5369.8 5101.4 5159.4 5344.4 5352.5 5358.9 5148.9 5186.2 5193.3 5235.5 5261.1 5212.5 5322.3 5359.9 5360.7 5362.8 5198.7 5248.2 5302.1 5318.6 5323.7 5557.2 5647.3 5655.8 5681.5 5808.2 6165.1 6177.6 6196.0 6219.9 6222.8 5934.1 6213.6 6222.1 6225.5 6227.0 5636.2 5655.2 5661.6 5671.2 5672.7 6215.0 6222.3 6223.5 6227.3 6326.9 5834.7 6007.7 6051.7 6066.0 6068.9 6186.4 6226.3 6226.9 6227.0 6228.3 6080.9 6082.6 6089.6 6091.8 6096.5 5933.9 5985.4 6056.9 6081.5 6097.2 4518.3 4554.9 4566.7 4570.0 4585.5 4027.4 4056.0 4075.9 4077.0 4092.7 5698.1 5748.0 5758.6 5772.4 5779.4 6125.9 6233.8 6244.0 6244.4 6272.5 5872.2 5877.2 5881.0 5884.8 5893.6 5048.6 5106.8 5214.1 5219.3 5227.2 6332.3 6471.7 6487.7 6493.3 6494.6 6186.6 6205.3 6211.2 6217.5 6219.8 6114.7 6208.7 6469.9 6483.2 6495.2 5973.6 6024.5 6034.1 6056.5 6095.7 6109.8 6118.4 6125.0 6128.1 6128.3 5737.8 5991.5 6010.5 6013.8 6019.7 5992.7 6106.5 6108.5 6119.3 6145.0 6014.7 6072.4 6079.0 6088.5 6099.6 5901.7 5945.1 6033.6 6104.7 6124.4 5823.9 5913.2 5918.0 5941.8 5953.1 17:37:47 INFO - PROCESS | 19667 | 17:37:47 INFO - PROCESS | 19667 | [#1] ext.html Cycles:180 Average:64358.90 Median:22315.17 stddev:126957.39 (568.9%) stddev-sans-first:127223.17 17:37:47 INFO - PROCESS | 19667 | Values: 559.5 617.6 651.2 686.1 691.9 35216.1 45442.5 46350.5 46458.0 46940.5 49684.3 50207.2 50551.7 50598.7 50600.2 8580.4 9358.4 9515.0 9520.8 9522.2 11007.1 11059.3 11280.5 11624.1 11638.3 11471.5 11549.7 11571.6 11598.8 11600.7 11255.7 11316.2 11334.8 11339.5 11340.7 11189.7 11265.0 11274.9 11276.0 11278.8 46134.0 46360.3 46539.8 46701.0 46712.5 3902.3 3978.4 4076.6 4117.8 4583.6 35167.3 35581.3 35636.8 36041.8 37569.0 4580.7 4631.1 4656.0 4702.1 4812.1 359497.0 361353.0 361742.0 362288.0 362493.0 64207.4 64658.0 64681.4 64747.0 64794.0 40080.0 41030.4 41369.6 43695.8 43707.6 16388.8 16589.1 16594.0 16597.8 16606.7 10781.8 10898.3 12206.9 12900.8 13033.2 21228.2 21270.9 21324.0 21436.6 21492.9 7262.9 7344.3 7397.7 7436.2 7632.0 1021.6 1036.5 1119.3 1250.0 1260.2 35384.5 35645.8 35755.3 35842.0 35847.3 1176.6 1193.7 1194.0 1199.4 1229.8 14914.3 18550.9 19153.8 19682.2 19974.5 602265.0 608880.0 608934.0 609914.0 610138.0 64405.7 64428.7 64438.4 64446.7 64853.7 1325.4 1427.6 1436.9 1439.3 1482.8 33835.3 36770.6 37031.8 37201.3 37289.9 55465.4 55623.7 56237.0 56269.2 56304.4 431105.0 431735.0 432525.0 433648.0 433870.0 27271.5 30256.2 30530.4 31082.7 31306.2 52208.0 53966.7 54154.0 54168.5 59358.0 46198.8 46454.3 46653.8 46949.0 48007.3 44331.3 46593.8 46764.5 46911.0 47040.0 45198.3 45765.8 46018.8 46039.5 46759.5 36719.6 40553.8 40859.0 41260.0 41787.0 30306.0 30744.7 30761.5 31077.4 31108.7 17:37:47 INFO - PROCESS | 19667 | 17:37:47 INFO - PROCESS | 19667 | [#2] jquery.html Cycles:180 Average:29872.07 Median:2352.48 stddev:85531.91 (3635.8%) stddev-sans-first:85743.25 17:37:47 INFO - PROCESS | 19667 | Values: 418.1 420.1 438.5 438.8 450.3 19545.9 21183.9 21203.5 21444.6 21756.8 19769.0 20018.4 20548.3 20718.0 20742.6 5844.8 6123.5 6130.3 6133.6 6134.1 5864.1 5917.1 5995.5 6024.2 6043.2 5984.4 5987.2 5988.4 5997.2 5999.3 5818.3 6030.2 6062.6 6147.6 6151.8 5932.1 5940.2 5980.9 5981.9 6001.2 15015.5 15285.2 15285.5 15353.2 15359.5 2314.5 2390.5 2425.7 2512.9 2888.4 11207.6 11366.5 14697.8 15454.7 16040.0 1981.3 1998.3 2038.8 2076.8 2099.3 63677.0 63984.3 77303.0 84978.0 90081.5 11587.2 12024.3 12109.8 12215.6 12266.3 17213.9 17226.6 17251.7 17265.8 17271.1 8534.2 8552.8 8663.3 8740.0 8740.4 7919.3 7968.9 8115.0 8162.6 8216.4 5144.3 5195.0 5209.2 5227.0 5308.7 1961.6 1975.2 2003.4 2078.9 2183.4 926.9 1035.5 1041.7 1057.9 1074.7 20347.7 20681.9 20862.5 20878.7 20939.4 1031.6 1042.3 1061.6 1070.7 1078.9 10753.4 11722.4 11725.9 11758.1 11807.1 501101.0 511773.0 512856.0 533659.0 544103.0 78964.0 81884.0 85534.0 87811.6 97886.0 986.8 989.5 1020.1 1020.4 1030.2 18595.9 18973.5 18983.6 19052.0 19349.3 31566.7 42919.4 43874.8 43944.6 44818.0 58776.7 59163.7 60220.7 60581.0 61113.7 8420.8 8489.8 8632.3 8711.0 8720.0 11836.4 13627.5 13672.9 13735.7 14201.6 12195.7 12358.9 12365.3 12428.0 12747.3 5692.0 9434.0 11902.9 12055.2 12205.1 12108.9 12292.3 12387.2 12628.7 12807.6 8479.9 8558.8 8587.7 8773.8 8920.3 10632.4 12311.9 12365.9 12386.4 12388.6 17:37:47 INFO - PROCESS | 19667 | 17:37:47 INFO - PROCESS | 19667 | [#3] mootools.html Cycles:180 Average:6425.01 Median:4707.86 stddev:3659.45 (77.7%) stddev-sans-first:3651.55 17:37:47 INFO - PROCESS | 19667 | Values: 1574.1 1586.0 1799.7 1875.7 1901.1 9012.5 9566.1 9717.9 9718.5 9726.4 8516.6 8801.5 8802.6 8905.2 9333.5 2441.4 2536.9 2539.7 2559.0 2561.1 2509.9 2542.6 2551.4 2556.5 2567.8 2470.4 2473.2 2480.7 2483.6 2516.6 2418.8 2429.8 2430.4 2433.5 2447.2 2463.4 2464.2 2468.0 2468.1 2468.9 9513.0 9577.7 9584.1 9586.0 9587.4 5626.6 5709.2 5713.4 5730.6 5894.4 3491.4 3560.4 3702.4 3716.5 3725.6 1197.1 1203.8 1230.4 1238.8 2137.7 13213.3 13800.0 13844.2 13856.0 13879.2 4931.0 4966.9 5045.3 5095.0 5117.7 11042.1 11052.5 11053.8 11058.1 11059.6 5824.3 5853.1 5863.9 5866.8 5870.9 5207.3 5254.6 5280.2 5453.5 5461.2 3998.8 4080.0 4126.5 4144.4 4188.6 1175.1 1175.6 1194.7 1207.9 1226.5 935.4 973.7 1008.0 1080.2 1103.4 6709.5 6833.4 6856.2 6861.7 6869.5 1048.3 1091.2 1101.8 1112.6 1163.6 4412.0 4438.7 4644.4 4681.6 4734.1 11264.4 12005.5 12122.2 12414.4 12503.0 12201.6 12400.4 12402.1 12416.3 12520.1 9088.6 9099.4 9404.3 9405.4 9409.8 6659.9 6779.3 6805.6 6846.9 6931.8 10920.0 11011.6 11077.3 11118.2 11196.3 9713.8 10994.6 11378.1 11488.5 11517.2 8636.3 8956.7 8963.7 8969.2 8989.1 9632.6 9634.1 9638.0 9738.5 9759.3 8705.5 8755.1 8813.6 8861.8 9032.2 8418.4 8602.4 8604.9 8700.6 8881.2 7572.4 7697.5 7830.8 7883.1 7979.8 5815.4 5847.7 5857.5 5892.9 6150.2 5958.6 6111.8 6159.5 6178.8 6200.8 17:37:47 INFO - PROCESS | 19667 | 17:37:47 INFO - PROCESS | 19667 | [#4] prototype.html Cycles:180 Average:4824.02 Median:5264.47 stddev:1403.49 (26.7%) stddev-sans-first:1366.55 17:37:47 INFO - PROCESS | 19667 | Values: 344.1 346.0 361.9 362.0 365.8 5592.6 5602.2 5635.3 5658.7 5678.6 5548.1 5600.4 5620.8 5637.0 5640.8 3878.2 4117.6 4351.1 4361.4 4364.9 4363.2 4363.4 4364.1 4379.9 4408.1 4376.4 4381.0 4400.6 4427.7 4440.6 4314.5 4353.7 4397.5 4408.2 4415.4 3842.0 3853.6 3863.9 3866.5 3874.8 5407.9 5410.0 5410.7 5423.9 5502.6 4844.2 4976.5 5014.6 5020.8 5052.7 5774.9 5780.9 5781.2 5789.6 5816.5 4268.4 4298.7 4302.3 4306.2 4306.8 5970.3 6008.9 6010.0 6077.3 6159.8 4319.7 4327.4 4344.0 4374.3 4379.5 4442.9 4443.6 4445.4 4446.4 4453.8 4921.3 4929.2 4935.8 5015.8 5039.0 5774.7 5775.2 5777.8 5826.5 5835.7 1509.5 1550.7 1551.6 1552.5 1556.1 997.7 1007.7 1011.5 1013.3 1029.5 4902.7 4933.3 4936.6 4939.3 4942.6 5168.4 5180.5 5228.2 5230.2 5265.0 4586.5 4891.8 4913.7 4935.0 4948.9 2785.9 2786.7 2788.5 2792.0 2796.3 5232.9 5263.9 5310.6 5354.2 5384.8 5073.0 5300.7 5305.9 5328.9 5843.7 6064.8 6135.2 6188.6 6191.2 6198.7 5940.5 5951.0 5966.3 5987.9 6014.6 5802.6 5866.6 5875.0 5911.2 5914.4 6176.8 6182.6 6183.4 6193.3 6243.3 4142.8 4420.0 4448.4 4454.3 4459.4 5953.0 5979.2 5990.8 5995.1 6006.9 5799.7 5829.6 5870.5 5890.7 5951.6 5654.0 5845.6 5851.9 5854.4 5870.5 5785.5 5800.7 5831.2 5834.3 5857.3 5681.9 5900.4 5940.2 5946.7 6008.7 5665.9 5738.0 5740.9 5756.5 5761.7 17:37:47 INFO - PROCESS | 19667 | 17:37:47 INFO - PROCESS | 19667 | [#5] yui.html Cycles:180 Average:22480.36 Median:4042.48 stddev:32731.42 (809.7%) stddev-sans-first:32782.79 17:37:47 INFO - PROCESS | 19667 | Values: 809.5 812.4 848.9 888.4 890.2 15526.5 16411.0 16441.3 16444.8 16460.6 15242.7 15280.2 15294.2 15304.8 15308.5 741.8 872.4 887.7 890.6 894.5 886.5 889.9 890.0 890.2 890.4 946.9 947.3 947.3 947.7 947.9 937.9 938.3 939.1 939.4 939.7 695.9 696.5 699.9 700.0 701.7 16045.0 16096.7 16158.8 16165.8 16180.5 16621.7 16658.1 16938.5 17695.3 17757.0 12803.9 12916.3 13003.3 13107.6 13137.5 4037.5 4113.7 4118.0 4163.1 4170.0 86261.0 86326.6 89001.6 89026.0 89485.0 11418.4 11680.7 11869.0 12640.9 12703.4 25346.9 25365.1 25384.0 25404.6 25426.9 9024.9 9038.6 9157.6 9280.6 9502.7 4969.6 5197.5 5202.8 5332.6 5350.7 3906.7 4014.1 4047.5 4054.0 4063.1 698.2 712.8 715.2 734.6 741.1 643.8 770.4 787.5 789.7 802.3 10182.6 10277.9 10353.4 10360.5 10394.3 740.6 754.4 773.7 788.2 798.3 4877.7 4972.4 4977.7 5139.1 5196.9 119815.0 133980.0 135743.0 141388.0 144065.3 61576.0 64208.4 105742.0 108877.0 109474.0 89879.2 91993.5 92097.5 94186.5 96681.5 10372.4 10405.8 11085.1 11206.6 11219.6 53700.5 53976.2 54286.7 54407.5 55302.0 75432.6 79832.6 80250.0 82958.6 85827.1 12628.9 12914.2 13357.5 13821.8 14331.6 14027.2 14056.5 14176.9 14451.6 14518.5 13191.5 13308.6 14746.1 14945.0 15514.7 14551.6 14729.3 15177.2 15317.7 16619.4 14976.8 15006.6 15737.6 16076.9 16104.3 11199.6 11214.7 11232.7 11987.6 12019.4 11184.9 11545.0 12700.9 12992.2 13152.4 17:37:47 INFO - PROCESS | 19667 | -------- Summary: end -------- 17:37:47 INFO - PROCESS | 19667 | 17:37:48 INFO - TEST-INFO | 19667: exit 0 17:37:48 INFO - TEST-OK | dromaeo_css | took 1113194ms 17:37:48 INFO - TEST-START | kraken 17:37:48 INFO - operating with platform_type : linux_ 17:37:48 INFO - Initialising browser for kraken test... 17:37:48 INFO - TEST-INFO | started process 19854 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIqAll/profile http://localhost:47388/getInfo.html) 17:37:52 INFO - PROCESS | 19854 | __metrics Screen width/height:1600/1200 17:37:52 INFO - PROCESS | 19854 | colorDepth:24 17:37:52 INFO - PROCESS | 19854 | Browser inner width/height: 1024/697 17:37:52 INFO - PROCESS | 19854 | __metrics 17:37:53 INFO - PROCESS | 19854 | 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" 17:37:53 INFO - PROCESS | 19854 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:37:54 INFO - TEST-INFO | 19854: exit 0 17:37:54 INFO - Browser initialized. 17:37:54 INFO - Running cycle 1/1 for kraken test... 17:37:54 INFO - TEST-INFO | started process 19939 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIqAll/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:38:05 INFO - PROCESS | 19939 | RSS: Main: 144175104 17:38:05 INFO - PROCESS | 19939 | 17:38:08 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47388/tests/kraken/driver.html?testName=audio-beat-detection) 17:38:09 INFO - PROCESS | 19939 | RSS: Main: 199151616 17:38:09 INFO - PROCESS | 19939 | 17:38:11 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47388/tests/kraken/driver.html?testName=audio-dft) 17:38:12 INFO - PROCESS | 19939 | RSS: Main: 183640064 17:38:12 INFO - PROCESS | 19939 | 17:38:15 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47388/tests/kraken/driver.html?testName=audio-fft) 17:38:15 INFO - PROCESS | 19939 | RSS: Main: 274862080 17:38:15 INFO - PROCESS | 19939 | 17:38:17 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47388/tests/kraken/driver.html?testName=audio-oscillator) 17:38:18 INFO - PROCESS | 19939 | RSS: Main: 180379648 17:38:18 INFO - PROCESS | 19939 | 17:38:20 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47388/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:38:21 INFO - PROCESS | 19939 | RSS: Main: 179982336 17:38:21 INFO - PROCESS | 19939 | 17:38:24 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47388/tests/kraken/driver.html?testName=imaging-darkroom) 17:38:24 INFO - PROCESS | 19939 | RSS: Main: 214806528 17:38:24 INFO - PROCESS | 19939 | 17:38:28 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47388/tests/kraken/driver.html?testName=imaging-desaturate) 17:38:28 INFO - PROCESS | 19939 | RSS: Main: 214077440 17:38:28 INFO - PROCESS | 19939 | 17:38:31 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47388/tests/kraken/driver.html?testName=json-parse-financial) 17:38:32 INFO - PROCESS | 19939 | RSS: Main: 213430272 17:38:32 INFO - PROCESS | 19939 | 17:38:33 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47388/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:38:34 INFO - PROCESS | 19939 | RSS: Main: 175828992 17:38:34 INFO - PROCESS | 19939 | 17:38:35 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:38:36 INFO - PROCESS | 19939 | RSS: Main: 183869440 17:38:36 INFO - PROCESS | 19939 | 17:38:38 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:38:39 INFO - PROCESS | 19939 | RSS: Main: 189800448 17:38:39 INFO - PROCESS | 19939 | 17:38:41 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:38:42 INFO - PROCESS | 19939 | RSS: Main: 197312512 17:38:42 INFO - PROCESS | 19939 | 17:38:44 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:38:45 INFO - PROCESS | 19939 | RSS: Main: 193343488 17:38:45 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47388/tests/kraken/driver.html?testName=ai-astar) 17:38:47 INFO - PROCESS | 19939 | RSS: Main: 244330496 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | __start_tp_report 17:38:47 INFO - PROCESS | 19939 | _x_x_mozilla_page_load 17:38:47 INFO - PROCESS | 19939 | _x_x_mozilla_page_load_details 17:38:47 INFO - PROCESS | 19939 | |i|pagename|runs| 17:38:47 INFO - PROCESS | 19939 | |0;ai-astar;111;109;120;110;111;111;109;109;111;110 17:38:47 INFO - PROCESS | 19939 | |1;audio-beat-detection;154;116;149;176;119;152;149;117;179;116 17:38:47 INFO - PROCESS | 19939 | |2;audio-dft;162;162;160;161;160;161;160;160;161;161 17:38:47 INFO - PROCESS | 19939 | |3;audio-fft;75;76;75;175;76;76;75;76;176;76 17:38:47 INFO - PROCESS | 19939 | |4;audio-oscillator;82;84;162;83;83;84;145;82;82;155 17:38:47 INFO - PROCESS | 19939 | |5;imaging-gaussian-blur;117;116;116;117;117;117;117;116;116;116 17:38:47 INFO - PROCESS | 19939 | |6;imaging-darkroom;146;146;146;147;147;146;146;146;147;147 17:38:47 INFO - PROCESS | 19939 | |7;imaging-desaturate;94;95;96;94;94;95;94;95;94;95 17:38:47 INFO - PROCESS | 19939 | |8;json-parse-financial;50;50;50;50;50;50;48;49;51;49 17:38:47 INFO - PROCESS | 19939 | |9;json-stringify-tinderbox;60;61;59;58;59;59;59;59;59;60 17:38:47 INFO - PROCESS | 19939 | |10;stanford-crypto-aes;74;93;74;73;78;76;72;78;73;75 17:38:47 INFO - PROCESS | 19939 | |11;stanford-crypto-ccm;122;129;118;124;119;121;117;122;155;117 17:38:47 INFO - PROCESS | 19939 | |12;stanford-crypto-pbkdf2;153;152;153;152;154;153;153;155;152;152 17:38:47 INFO - PROCESS | 19939 | |13;stanford-crypto-sha256-iterative;63;61;63;62;76;62;64;60;63;59 17:38:47 INFO - PROCESS | 19939 | __end_tp_report 17:38:47 INFO - PROCESS | 19939 | __start_cc_report 17:38:47 INFO - PROCESS | 19939 | _x_x_mozilla_cycle_collect,1676 17:38:47 INFO - PROCESS | 19939 | __end_cc_report 17:38:47 INFO - PROCESS | 19939 | __startTimestamp1476837527324__endTimestamp 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | ------- Summary: start ------- 17:38:47 INFO - PROCESS | 19939 | Number of tests: 14 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:3.25 (2.9%) stddev-sans-first:3.44 17:38:47 INFO - PROCESS | 19939 | Values: 111.0 109.0 120.0 110.0 111.0 111.0 109.0 109.0 111.0 110.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:150.50 stddev:24.42 (16.2%) stddev-sans-first:25.56 17:38:47 INFO - PROCESS | 19939 | Values: 154.0 116.0 149.0 176.0 119.0 152.0 149.0 117.0 179.0 116.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.71 17:38:47 INFO - PROCESS | 19939 | Values: 162.0 162.0 160.0 161.0 160.0 161.0 160.0 160.0 161.0 161.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#3] audio-fft Cycles:10 Average:95.60 Median:76.00 stddev:42.11 (55.4%) stddev-sans-first:44.00 17:38:47 INFO - PROCESS | 19939 | Values: 75.0 76.0 75.0 175.0 76.0 76.0 75.0 76.0 176.0 76.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#4] audio-oscillator Cycles:10 Average:104.20 Median:82.50 stddev:34.61 (41.9%) stddev-sans-first:35.76 17:38:47 INFO - PROCESS | 19939 | Values: 82.0 84.0 162.0 83.0 83.0 84.0 145.0 82.0 82.0 155.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 17:38:47 INFO - PROCESS | 19939 | Values: 117.0 116.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 17:38:47 INFO - PROCESS | 19939 | Values: 146.0 146.0 146.0 147.0 147.0 146.0 146.0 146.0 147.0 147.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 17:38:47 INFO - PROCESS | 19939 | Values: 94.0 95.0 96.0 94.0 94.0 95.0 94.0 95.0 94.0 95.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 17:38:47 INFO - PROCESS | 19939 | Values: 50.0 50.0 50.0 50.0 50.0 50.0 48.0 49.0 51.0 49.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.82 (1.4%) stddev-sans-first:0.83 17:38:47 INFO - PROCESS | 19939 | Values: 60.0 61.0 59.0 58.0 59.0 59.0 59.0 59.0 59.0 60.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#10] stanford-crypto-aes Cycles:10 Average:76.60 Median:75.50 stddev:6.11 (8.1%) stddev-sans-first:6.41 17:38:47 INFO - PROCESS | 19939 | Values: 74.0 93.0 74.0 73.0 78.0 76.0 72.0 78.0 73.0 75.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:122.00 stddev:11.35 (9.3%) stddev-sans-first:12.01 17:38:47 INFO - PROCESS | 19939 | Values: 122.0 129.0 118.0 124.0 119.0 121.0 117.0 122.0 155.0 117.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.90 Median:153.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 17:38:47 INFO - PROCESS | 19939 | Values: 153.0 152.0 153.0 152.0 154.0 153.0 153.0 155.0 152.0 152.0 17:38:47 INFO - PROCESS | 19939 | 17:38:47 INFO - PROCESS | 19939 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:4.72 (7.5%) stddev-sans-first:5.00 17:38:47 INFO - PROCESS | 19939 | Values: 63.0 61.0 63.0 62.0 76.0 62.0 64.0 60.0 63.0 59.0 17:38:47 INFO - PROCESS | 19939 | -------- Summary: end -------- 17:38:47 INFO - PROCESS | 19939 | 17:38:48 INFO - TEST-INFO | 19939: exit 0 17:38:48 INFO - TEST-OK | kraken | took 60007ms 17:38:48 INFO - SUITE-END | took 1173s 17:38:48 INFO - Completed test suite (00:19:33) 17:38:48 INFO - javascript benchmark 17:38:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3234.288456539561, 3247.8957154567443, 3253.3168034712694, 3272.296456261903, 3291.7366610671147, 5614.522765564927, 5754.338251101125, 5762.106873434619, 5780.653160810344, 5816.040353341694, 5815.360310365867, 5824.242848429709, 5847.883042339161, 5849.85375365615, 5855.47300742933, 5263.526282634562, 5307.893842123155, 5329.067413202693, 5359.115745901921, 5369.785208591652, 5101.38783345999, 5159.355080614923, 5344.438833922441, 5352.5182733553975, 5358.866028349303, 5148.8712782180555, 5186.196139598368, 5193.298904647881, 5235.476452354772, 5261.079311120558, 5212.50481204285, 5322.281491998569, 5359.946400535984, 5360.705161216131, 5362.758675859557, 5198.740062996872, 5248.239005344204, 5302.072137375943, 5318.574514038867, 5323.707196104213, 5557.166425036236, 5647.265855438808, 5655.830325090254, 5681.460261275172, 5808.157817116495, 6165.106059621375, 6177.598456100339, 6196.0, 6219.906701399483, 6222.751089956397, 5934.050551911673, 6213.596116252429, 6222.066689996492, 6225.470834979033, 6226.968865155691, 5636.210930469738, 5655.1517272409055, 5661.575381846378, 5671.2060311556415, 5672.716364181816, 6215.0, 6222.315545290015, 6223.471004964629, 6227.252729672377, 6326.905096423558, 5834.67909264986, 6007.669578173155, 6051.667158306335, 6066.0, 6068.939310606926, 6186.443220110212, 6226.25284965807, 6226.875462490725, 6226.968865155736, 6228.283747369111, 6080.8783824324155, 6082.57421980466, 6089.604175728564, 6091.756329746849, 6096.512279017668, 5933.851653708605, 5985.40145985398, 6056.909146362808, 6081.452669259699, 6097.17844926735, 4518.254488009442, 4554.886127846764, 4566.74882881438, 4569.95430045702, 4585.495595484561, 4027.4160246764054, 4056.0468289951837, 4075.8777236682945, 4076.9796151019054, 4092.6930480214105, 5698.145278208219, 5748.0, 5758.568107391962, 5772.422757724194, 5779.393163717833, 6125.907024958145, 6233.844153896189, 6244.0, 6244.375562443719, 6272.466840318532, 5872.17789509469, 5877.206577112035, 5881.0, 5884.7940322088525, 5893.5579831512805, 5048.570871475892, 5106.846794596168, 5214.113600687816, 5219.347581552306, 5227.2420499028185, 6332.271788744353, 6471.741130354733, 6487.6756162191705, 6493.25327587324, 6494.6103233806125, 6186.628802271878, 6205.2553693557065, 6211.192544969125, 6217.533684973555, 6219.844503887439, 6114.724837382418, 6208.689565521616, 6469.935300646934, 6483.157189565326, 6495.220573531206, 5973.611715238496, 6024.487918526799, 6034.064719968412, 6056.454919057306, 6095.725692343766, 6109.84725381869, 6118.449339559461, 6125.0, 6128.050152226412, 6128.325884152829, 5737.799177028784, 5991.520678345809, 6010.4891084258325, 6013.819585412444, 6019.72911219005, 5992.700364981646, 6106.542009349228, 6108.4807791338235, 6119.296280927751, 6145.0, 6014.72933717975, 6072.423119803611, 6079.0273556230795, 6088.482478989158, 6099.634021958696, 5901.704914754331, 5945.137954996588, 6033.577649564489, 6104.725287362168, 6124.356942520971, 5823.912641310469, 5913.231279933753, 5918.0, 5941.7623295067715, 5953.1070339449425], "value": 5660.778385575957, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.504838218177, 617.6016469377251, 651.1892693596471, 686.0566720758957, 691.8616276744652, 35216.11959701009, 45442.545574544245, 46350.536494635046, 46458.0, 46940.53059469404, 49684.254736178984, 50207.24689129666, 50551.74724126383, 50598.74700626501, 50600.24099638508, 8580.39937204396, 9358.4384936904, 9515.00092490289, 9520.761980950463, 9522.238220942343, 11007.06439952605, 11059.281146725478, 11280.548778048867, 11624.07007439407, 11638.301701897872, 11471.483783229733, 11549.6535103947, 11571.594994175206, 11598.826017609743, 11600.651980440558, 11255.66233013011, 11316.151288653302, 11334.829977550344, 11339.546418143264, 11340.659780206606, 11189.720256993558, 11265.0, 11274.943625281903, 11276.041536469409, 11278.774424511465, 46134.0, 46360.304595431095, 46539.767301163614, 46701.0, 46712.53287467116, 3902.3366027775346, 3978.42312864633, 4076.5515793262716, 4117.794110294473, 4583.633309335245, 35167.29665406678, 35581.28837423237, 35636.821815890755, 36041.81979090114, 37569.0, 4580.725156490621, 4631.0506346198945, 4655.976720116411, 4702.130105930414, 4812.133815913135, 359497.0, 361353.0, 361742.0, 362288.0, 362493.0, 64207.39481512999, 64658.0, 64681.35318646848, 64747.0, 64794.0, 40080.0, 41030.38454423186, 41369.58630413718, 43695.781521092824, 43707.56292437035, 16388.83611163873, 16589.087600182105, 16594.0, 16597.83402165987, 16606.667866642598, 10781.838272425919, 10898.291611045257, 12206.877931220752, 12900.80648790269, 13033.15284506523, 21228.150873964867, 21270.893645531673, 21324.0, 21436.571268574542, 21492.892535537536, 7262.854742905112, 7344.25576415515, 7397.741079062207, 7436.191998401657, 7632.0, 1021.6015753856002, 1036.5439206749156, 1119.2501024313729, 1249.9750603539455, 1260.1682889293022, 35384.46923296153, 35645.82177089098, 35755.28489430249, 35842.0, 35847.28305433877, 1176.5529098942347, 1193.7493126443371, 1193.9641810745518, 1199.3763243113533, 1229.8032314829763, 14914.328855201325, 18550.907245463957, 19153.808461915203, 19682.212711491666, 19974.50063748418, 602265.0, 608880.0, 608934.0, 609914.0, 610138.0, 64405.67797160984, 64428.67785661042, 64438.355616444176, 64446.67776661087, 64853.67573162198, 1325.3969443903002, 1427.5717284814514, 1436.892233082544, 1439.2579293835943, 1482.770170623555, 33835.323293534486, 36770.63229367672, 37031.814840925625, 37201.25597488089, 37289.881303560935, 55465.44534554603, 55623.72188139033, 56237.0, 56269.15596266141, 56304.43695562992, 431105.0, 431735.0, 432525.0, 433648.0, 433870.0, 27271.454570908867, 30256.243593909447, 30530.389392212477, 31082.689173108884, 31306.21734456657, 52208.0, 53966.730166350484, 54154.0, 54168.45831541634, 59358.0, 46198.769006154755, 46454.30318545292, 46653.76673116613, 46949.0, 48007.279890800964, 44331.33502997393, 46593.76703116463, 46764.53235467738, 46911.0, 47040.0, 45198.32202517031, 45765.77117114393, 46018.76990615026, 46039.53960460486, 46759.53240467688, 36719.63280367269, 40553.797231013654, 40859.0, 41260.0, 41786.95532611709, 30306.0, 30744.692553074183, 30761.538576920917, 31077.378452430374, 31108.688913110578], "value": 21957.884623078535, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.10942692065896, 420.1171088569031, 438.4519350811486, 438.75649014796784, 450.31102413307633, 19545.902270488645, 21183.894080529593, 21203.469913252156, 21444.571108577857, 21756.782432175674, 19769.0, 20018.399448016546, 20548.280810171647, 20718.0, 20742.585148297025, 5844.795432159875, 6123.540734444912, 6130.325664176937, 6133.601315914473, 6134.110553969672, 5864.091065884794, 5917.100770170248, 5995.490383317423, 6024.21685180928, 6043.214382130339, 5984.431479009487, 5987.161797348353, 5988.3712210217955, 5997.190379298804, 5999.340072592011, 5818.301803783531, 6030.216071910668, 6062.605930614496, 6147.569670123094, 6151.784687535915, 5932.080527518242, 5940.168376427304, 5980.910286345708, 5981.9401805981815, 6001.1898393717165, 15015.549533514011, 15285.235738213156, 15285.465008724752, 15353.232338383148, 15359.539213823604, 2314.456102815837, 2390.5099454611764, 2425.6604075429454, 2512.9120480783085, 2888.3501212227293, 11207.551697932073, 11366.488508017159, 14697.77953330701, 15454.690906181813, 16040.0, 1981.3362523554626, 1998.3105828489292, 2038.8063134002393, 2076.8442366822396, 2099.3374184227537, 63677.0, 63984.313880828755, 77303.0, 84977.99999999939, 90081.54959225227, 11587.246828956091, 12024.33866137371, 12109.818352724717, 12215.633530994084, 12266.325352105541, 17213.913930430266, 17226.569335766457, 17251.74122388165, 17265.827341726672, 17271.13644317779, 8534.231919127309, 8552.828943421096, 8663.306935445275, 8740.0, 8740.388172827841, 7919.3268572170855, 7968.880466793003, 8114.959425202836, 8162.551059691774, 8216.424850260544, 5144.305518754995, 5194.97402512985, 5209.192575150858, 5226.97386513065, 5308.654937429132, 1961.6370971370343, 1975.170428420067, 2003.3889663652435, 2078.8856612886134, 2183.3559100065518, 926.893407258174, 1035.5236591168148, 1041.6979076067855, 1057.8730552333768, 1074.661481633283, 20347.69478457824, 20681.89659051695, 20862.478438039172, 20878.686819697716, 20939.37181884546, 1031.6079889641887, 1042.3329069395593, 1061.5912873543587, 1070.7483741320784, 1078.926468164166, 10753.408562529135, 11722.413879306172, 11725.882741172649, 11758.118141139448, 11807.114466415052, 501101.0, 511773.0, 512856.0, 533659.0, 544103.0, 78964.0, 81884.0, 85534.0, 87811.56094219488, 97886.0, 986.8470387090013, 989.450854775592, 1020.0819262663514, 1020.4183615339185, 1030.175859312547, 18595.907020464812, 18973.525661858013, 18983.620327593646, 19052.0, 19349.322773702854, 31566.684333157293, 42919.356209657504, 43874.78062609667, 43944.56055439405, 44818.0, 58776.70611646914, 59163.70418147882, 60220.69889650699, 60581.0, 61113.69443152934, 8420.789480263042, 8489.787755306166, 8632.309415246891, 8711.0, 8719.956400217958, 11836.408179590813, 13627.454901803816, 13672.86327136716, 13735.656608584864, 14201.573952781433, 12195.695107622381, 12358.938205308916, 12365.258084514959, 12428.0, 12747.298898560668, 5692.032354499828, 9433.952830235805, 11902.940485297519, 12055.221465246867, 12205.145639805129, 12108.939455302667, 12292.323922184365, 12387.19483233587, 12628.684282893002, 12807.61577152687, 8479.872801908099, 8558.786030349042, 8587.742367728979, 8773.824523509367, 8920.286377089948, 10632.362058276529, 12311.876881231074, 12365.876341236473, 12386.380680965736, 12388.56640017595], "value": 9555.968708644923, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1574.0948954351247, 1585.9920700396497, 1799.6670615936052, 1875.7373967644523, 1901.0969789350052, 9012.459252444849, 9566.09122133398, 9717.854232186504, 9718.514074296274, 9726.416415015105, 8516.61675224615, 8801.471911685287, 8802.559872006406, 8905.15401036902, 9333.53332333384, 2441.3896525868577, 2536.8985240590355, 2539.669842920427, 2559.001989224204, 2561.078011915709, 2509.912153074643, 2542.6313184588225, 2551.374913146282, 2556.463142740027, 2567.833090849098, 2470.4441500662388, 2473.208573256559, 2480.6651102101164, 2483.5777917754026, 2516.6032851767263, 2418.806495480357, 2429.842060266077, 2430.3802530354646, 2433.537627850703, 2447.229122826313, 2463.3841539615096, 2464.1621848571394, 2468.025130073629, 2468.123816045296, 2468.90124395024, 9512.95243523778, 9577.712668619952, 9584.137427631547, 9585.952070239673, 9587.42475451468, 5626.634268772516, 5709.200711900337, 5713.371529131792, 5730.570207234432, 5894.440028197314, 3491.389006923778, 3560.43033114703, 3702.3705969985167, 3716.4982727331735, 3725.5715592706642, 1197.0603076584925, 1203.7592481503648, 1230.4278510492575, 1238.7708273969254, 2137.700721898935, 13213.33933303331, 13800.0, 13844.238566878817, 13856.030077894551, 13879.167249965034, 4931.038447502774, 4966.90066198674, 5045.31888195089, 5095.031943930641, 5117.718525481134, 11042.116630669527, 11052.502637381338, 11053.834192487118, 11058.060064894413, 11059.557617695202, 5824.271966004249, 5853.063509838491, 5863.912041319299, 5866.794662186889, 5870.9412905870395, 5207.297014903015, 5254.632175747737, 5280.1815718563685, 5453.481919217747, 5461.1808228766, 3998.8400463981698, 4079.97960010204, 4126.504819421689, 4144.357624568197, 4188.643965262987, 1175.0951767139425, 1175.6179241746468, 1194.713268815478, 1207.9154459187948, 1226.47874653272, 935.3919952031234, 973.732223638505, 1007.9596816127273, 1080.1844607321423, 1103.3765922253901, 6709.496787740842, 6833.384995350442, 6856.211535673362, 6861.656917154122, 6869.450443964471, 1048.3028785857473, 1091.170710260208, 1101.7961677089763, 1112.5827814569536, 1163.6334554615269, 4412.007298357844, 4438.7114837535455, 4644.419447569054, 4681.648876334289, 4734.07685501324, 11264.436778161224, 12005.519779208735, 12122.212056216318, 12414.441350139265, 12502.999760019358, 12201.633950981484, 12400.379981000913, 12402.069844761498, 12416.255024698366, 12520.123591348518, 9088.636454541744, 9099.408538444979, 9404.294677899048, 9405.43567385959, 9409.764755881159, 6659.866802664016, 6779.288174741779, 6805.591664499948, 6846.863062738617, 6931.757388491378, 10919.962603552649, 11011.559537618405, 11077.33535987811, 11118.166137539878, 11196.328220306808, 9713.757156070873, 10994.560217591206, 11378.08975281959, 11488.483018264365, 11517.193796434169, 8636.309095272489, 8956.68651597191, 8963.686270980485, 8969.237614802814, 8989.146031127033, 9632.566534505824, 9634.13292803651, 9637.951810240904, 9738.513074346112, 9759.316847820868, 8705.52119633401, 8755.124487551195, 8813.603387847434, 8861.778455538662, 9032.187103160748, 8418.368622353226, 8602.354823388396, 8604.913950860411, 8700.564971751513, 8881.156290152425, 7572.432067594842, 7697.498987697345, 7830.765077047698, 7883.054033516015, 7979.800504987422, 5815.389384114607, 5847.707614619167, 5857.531397488276, 5892.882142357214, 6150.231221097363, 5958.582899197015, 6111.816645500642, 6159.5072394209255, 6178.845528861634, 6200.782972595937], "value": 5123.57558118551, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.0744397570535, 345.95329630499884, 361.91856832212756, 361.9800910949897, 365.84085922623643, 5592.5805564582615, 5602.24369710089, 5635.28123922204, 5658.688772117537, 5678.558219965414, 5548.140038294061, 5600.355959064709, 5620.803271885485, 5636.985342638323, 5640.830775076744, 3878.224355128971, 4117.608827161415, 4351.064521127957, 4361.433013708213, 4364.890877728067, 4363.236433624119, 4363.410939523172, 4364.105358401536, 4379.890502737425, 4408.118376324732, 4376.365427013097, 4380.956190438103, 4400.581944715247, 4427.71219870709, 4440.600345968862, 4314.546972567882, 4353.651707863363, 4397.516273209944, 4408.20652282589, 4415.403920470743, 3841.9615803841884, 3853.5953724859046, 3863.884083477472, 3866.516685414323, 3874.8062596870045, 5407.864348486823, 5409.972950135263, 5410.729463526808, 5423.945760542383, 5502.642328248651, 4844.249141383091, 4976.4525902150735, 5014.59883209346, 5020.7740651670765, 5052.671576347562, 5774.884502309972, 5780.913286300709, 5781.207825574085, 5789.594728369016, 5816.505597024258, 4268.423762792015, 4298.688899885534, 4302.333138363559, 4306.160298741746, 4306.827726890952, 5970.34326224115, 6008.879822403527, 6009.969950150265, 6077.270727512682, 6159.784407545759, 4319.682496838432, 4327.37253098299, 4344.0, 4374.278244089754, 4379.540148284448, 4442.888927776831, 4443.600075993111, 4445.3998710174355, 4446.444194475691, 4453.821847126143, 4921.286413470027, 4929.2359684248995, 4935.827246046371, 5015.774290156952, 5038.974805126024, 5774.6718254801635, 5775.191473193756, 5777.826665200051, 5826.475617194473, 5835.67903765297, 1509.5471358592597, 1550.71311807316, 1551.604340893065, 1552.5251011538858, 1556.0819116720963, 997.7106639074732, 1007.7480629842539, 1011.5245834457793, 1013.2653825976108, 1029.4543891737392, 4902.656814022984, 4933.28467372229, 4936.6297527685565, 4939.333190019373, 4942.604591632661, 5168.415792103948, 5180.4560521145495, 5228.163493840967, 5230.215467679878, 5265.025260727677, 4586.541345865387, 4891.779869905863, 4913.656044076952, 4934.963657631865, 4948.931662369282, 2785.916422507328, 2786.679531853863, 2788.525950588405, 2791.972080279171, 2796.3428594280326, 5232.895342093137, 5263.921041184385, 5310.601704872072, 5354.196870469461, 5384.757685904144, 5073.036123136666, 5300.655457395257, 5305.904167853676, 5328.907573947409, 5843.707814609253, 6064.848378790477, 6135.190118914931, 6188.566800323934, 6191.226096737908, 6198.659073750897, 5940.465358117617, 5951.0, 5966.284045914518, 5987.910181347196, 6014.6391216527145, 5802.622829516068, 5866.6186697864505, 5875.00249915031, 5911.172435858897, 5914.408559144051, 6176.81469555914, 6182.598131121463, 6183.3816618337805, 6193.349698281616, 6243.283097148321, 4142.798588409328, 4420.0, 4448.399466072168, 4454.28731402974, 4459.420275364311, 5952.970235148797, 5979.222701048836, 5990.820275391745, 5995.070764031582, 6006.939930600638, 5799.739011744397, 5829.562782791223, 5870.530357571299, 5890.705464726832, 5951.642901425928, 5653.953674121373, 5845.561582881216, 5851.853703657443, 5854.414558544282, 5870.503021729432, 5785.508231800344, 5800.7099645018425, 5831.212786273799, 5834.329052158886, 5857.297124344936, 5681.857953551194, 5900.409959004065, 5940.227770389822, 5946.73239704223, 6008.699565021819, 5665.886682266414, 5737.971310143423, 5740.885182296414, 5756.539476841926, 5761.740721667451], "value": 4384.323436819854, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [809.522381794741, 812.4475356757403, 848.9023762267337, 888.4402826219483, 890.2299510923053, 15526.456574019912, 16411.015339079648, 16441.26014329355, 16444.83555164448, 16460.588485287873, 15242.695146097072, 15280.235988200573, 15294.158821264826, 15304.84695153048, 15308.46420375287, 741.7737590035038, 872.402404353017, 887.702619622424, 890.6437425029975, 894.4990805149138, 886.4636894678723, 889.888763904512, 890.0, 890.2121622364222, 890.3989806880362, 946.8958414574391, 947.275334369208, 947.3368641950627, 947.663579429303, 947.8625599288133, 937.9249660027182, 938.3290946972879, 939.065629698451, 939.4175611121115, 939.6899023322321, 695.8538706871564, 696.5472442912097, 699.9055127557765, 700.0444393403019, 701.6772284749021, 16045.0, 16096.678066438722, 16158.75761863573, 16165.757513637305, 16180.514584562365, 16621.6675666486, 16658.0838053907, 16938.491845244538, 17695.29218831245, 17757.02336371499, 12803.935980320131, 12916.289604071771, 13003.349832508431, 13107.606771796862, 13137.474501019948, 4037.4751282333405, 4113.691473139526, 4117.958820411817, 4163.10493243954, 4170.0, 86261.0, 86326.5683671584, 89001.55499222463, 89026.0, 89485.0, 11418.371989540488, 11680.70798230051, 11868.953582355232, 12640.936795315965, 12703.364831758376, 25346.873265633552, 25365.112221072173, 25384.0, 25404.618930716053, 25426.872865635552, 9024.909750902538, 9038.63845446188, 9157.633694652139, 9280.628774849065, 9502.714918552454, 4969.552740253394, 5197.532222099953, 5202.76587553561, 5332.600054995891, 5350.732463376816, 3906.7069969752383, 4014.116894283253, 4047.494063242095, 4054.0, 4063.0858056936954, 698.2284575544053, 712.7968528969323, 715.1775458223085, 734.5629350536423, 741.1143683298407, 643.8326035230874, 770.4414299632722, 787.4566549081115, 789.7157023471545, 802.3460879383284, 10182.643607473703, 10277.94861025705, 10353.378797272188, 10360.533775980099, 10394.324368915995, 740.6185814305637, 754.3550264523753, 773.6905237904883, 788.1960400391569, 798.3134504326275, 4877.6829506081995, 4972.353594032753, 4977.651564390458, 5139.1263485206855, 5196.870078248074, 119815.0, 133980.0, 135743.0, 141388.0, 144065.27967360095, 61576.0, 64208.35791642024, 105742.0, 108877.0, 109474.0, 89879.15785473923, 91993.54003229941, 92097.53951230201, 94186.5290673556, 96681.51659241659, 10372.377657340583, 10405.843912341472, 11085.113190944867, 11206.551737930391, 11219.55121795119, 53700.46299537111, 53976.19035714389, 54286.72856635691, 54407.45592544024, 55302.0, 75432.62283688546, 79832.60083699544, 80250.0, 82958.58520707599, 85827.14172858192, 12628.873711263137, 12914.160579562298, 13357.53248636293, 13821.792673110112, 14331.570052898429, 14027.158370497802, 14056.508022219174, 14176.858231417555, 14451.638709032359, 14518.491852785102, 13191.472341106248, 13308.600741977756, 14746.11523308605, 14945.0, 15514.689706206038, 14551.636209094857, 14729.263536822902, 15177.24113794328, 15317.693646126792, 16619.41832035873, 14976.775348369565, 15006.624834379229, 15737.606559835638, 16076.919615402316, 16104.27530761142, 11199.552017919192, 11214.66356009321, 11232.66302010941, 11987.64037078889, 12019.399030048637, 11184.88815111871, 11545.0, 12700.936495317834, 12992.198691296713, 13152.408141633457], "value": 8215.975160035494, "unit": "score"}], "name": "dromaeo_css", "value": 7765.210104978772, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 120.0, 110.0, 111.0, 111.0, 109.0, 109.0, 111.0, 110.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 116.0, 149.0, 176.0, 119.0, 152.0, 149.0, 117.0, 179.0, 116.0], "value": 142.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 161.0, 160.0, 161.0, 160.0, 160.0, 161.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 175.0, 76.0, 76.0, 75.0, 76.0, 176.0, 76.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 162.0, 83.0, 83.0, 84.0, 145.0, 82.0, 82.0, 155.0], "value": 104.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 50.0, 50.0, 50.0, 48.0, 49.0, 51.0, 49.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 58.0, 59.0, 59.0, 59.0, 59.0, 59.0, 60.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 93.0, 74.0, 73.0, 78.0, 76.0, 72.0, 78.0, 73.0, 75.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 129.0, 118.0, 124.0, 119.0, 121.0, 117.0, 122.0, 155.0, 117.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 153.0, 152.0, 154.0, 153.0, 153.0, 155.0, 152.0, 152.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 63.0, 62.0, 76.0, 62.0, 64.0, 60.0, 63.0, 59.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1498.1000000000001, "alertThreshold": 2.0}]} 17:38:48 INFO - Return code: 0 17:38:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:38:48 INFO - # TBPL SUCCESS # 17:38:48 INFO - Running post-action listener: _resource_record_post_action 17:38:48 INFO - [mozharness: 2016-10-19 00:38:48.655211Z] Finished run-tests step (success) 17:38:48 INFO - Running post-run listener: _resource_record_post_run 17:38:48 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 289316864; Read time: 36; Write time: 203800 17:38:48 INFO - TinderboxPrint: CPU usage
13.0% 17:38:48 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 36 17:38:48 INFO - TinderboxPrint: I/O write bytes / time
289,316,864 / 203,800 17:38:48 INFO - TinderboxPrint: CPU idle
8,246.7 (87.0%) 17:38:48 INFO - TinderboxPrint: CPU user
1,184.3 (12.5%) 17:38:48 INFO - TinderboxPrint: Swap in / out
0 / 0 17:38:48 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 70115328; Read time: 0; Write time: 75012 17:38:48 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 219201536; Read time: 36; Write time: 128788 17:38:49 INFO - Running post-run listener: _upload_blobber_files 17:38:49 INFO - Blob upload gear active. 17:38:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:38:49 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 17:38:49 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:38:49 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:38:49 INFO - (blobuploader) - INFO - Open directory for files ... 17:38:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:38:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:38:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:50 INFO - (blobuploader) - INFO - Done attempting. 17:38:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:38:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:38:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:51 INFO - (blobuploader) - INFO - Done attempting. 17:38:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:38:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:38:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:54 INFO - (blobuploader) - INFO - Done attempting. 17:38:54 INFO - (blobuploader) - INFO - Iteration through files over. 17:38:54 INFO - Return code: 0 17:38:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:38:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:38:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/92e65819ca683cb791fa1b57c25dea1c0027fb87eaca5f8dc8a33a099a9ec3096ecf78eba5f954b1e302a0c4102d02400ed13e2d073219a96f5a26d76f72887c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d3af16657b49e74547c11a27251fde616c099267636d0b1e1238b2489a57e44518c62d118ab3bd4a82118312d9729166ad3733c2dc8b0cf88ac9c29e1fed0e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71da64e7d25ef27453e23fbbcab06095595d1877405cd267c39bf2bfa80bb85e93d1859cebe99993bc4e79d9efcd2e8a44f2710af3616652125365b54f9522cc"} 17:38:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:38:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:38:54 INFO - Contents: 17:38:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/92e65819ca683cb791fa1b57c25dea1c0027fb87eaca5f8dc8a33a099a9ec3096ecf78eba5f954b1e302a0c4102d02400ed13e2d073219a96f5a26d76f72887c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d3af16657b49e74547c11a27251fde616c099267636d0b1e1238b2489a57e44518c62d118ab3bd4a82118312d9729166ad3733c2dc8b0cf88ac9c29e1fed0e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71da64e7d25ef27453e23fbbcab06095595d1877405cd267c39bf2bfa80bb85e93d1859cebe99993bc4e79d9efcd2e8a44f2710af3616652125365b54f9522cc"} 17:38:54 INFO - Running post-run listener: copy_logs_to_upload_dir 17:38:54 INFO - Copying logs to upload dir... 17:38:54 INFO - mkdir: /builds/slave/test/build/upload/logs 17:38:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.396031 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-18 17:38:54.280586) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 17:38:54.284854) ========= 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=0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/92e65819ca683cb791fa1b57c25dea1c0027fb87eaca5f8dc8a33a099a9ec3096ecf78eba5f954b1e302a0c4102d02400ed13e2d073219a96f5a26d76f72887c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d3af16657b49e74547c11a27251fde616c099267636d0b1e1238b2489a57e44518c62d118ab3bd4a82118312d9729166ad3733c2dc8b0cf88ac9c29e1fed0e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71da64e7d25ef27453e23fbbcab06095595d1877405cd267c39bf2bfa80bb85e93d1859cebe99993bc4e79d9efcd2e8a44f2710af3616652125365b54f9522cc"} build_url:https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011376 build_url: 'https://queue.taskcluster.net/v1/task/RX3A7rUTQq213eqI_RlnDA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/92e65819ca683cb791fa1b57c25dea1c0027fb87eaca5f8dc8a33a099a9ec3096ecf78eba5f954b1e302a0c4102d02400ed13e2d073219a96f5a26d76f72887c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4d3af16657b49e74547c11a27251fde616c099267636d0b1e1238b2489a57e44518c62d118ab3bd4a82118312d9729166ad3733c2dc8b0cf88ac9c29e1fed0e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71da64e7d25ef27453e23fbbcab06095595d1877405cd267c39bf2bfa80bb85e93d1859cebe99993bc4e79d9efcd2e8a44f2710af3616652125365b54f9522cc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 17:38:54.311033) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:38:54.311457) ========= 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=0e2a256762fed810e2f0f810000001b3-1476835356.787042-352237963 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:38:54.396416) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 17:38:54.396753) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 17:38:54.397069) ========= ========= Total master_lag: 0.18 =========