builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1476986119.95 results: success (0) buildid: 20161020074150 builduid: 9712897ee2bf48d3b9fcf4e012fff591 revision: 69c68bce430d468037bc82223acb9ea4155dbc7d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:19.947771) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:19.948197) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:19.948467) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:19.965838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:19.966207) ========= 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=745a4373a2a16df560f216e000000264-1476986118.141679-1378209174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-20 10:55:20-- 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% 9.76M=0.001s 2016-10-20 10:55:20 (9.76 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121481 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:20.101087) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:20.101410) ========= 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=745a4373a2a16df560f216e000000264-1476986118.141679-1378209174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018607 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:20.153322) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 10:55:20.156714) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 69c68bce430d468037bc82223acb9ea4155dbc7d --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 69c68bce430d468037bc82223acb9ea4155dbc7d --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=745a4373a2a16df560f216e000000264-1476986118.141679-1378209174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-20 10:55:20,294 truncating revision to first 12 chars 2016-10-20 10:55:20,294 Setting DEBUG logging. 2016-10-20 10:55:20,294 attempt 1/10 2016-10-20 10:55:20,294 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/69c68bce430d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 10:55:21,656 unpacking tar archive at: mozilla-beta-69c68bce430d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.728672 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 10:55:21.899312) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:21.899827) ========= script_repo_revision: 69c68bce430d468037bc82223acb9ea4155dbc7d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:21.900297) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:21.900700) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 10:55:21.912470) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-10-20 10:55:21.912850) ========= /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=745a4373a2a16df560f216e000000264-1476986118.141679-1378209174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:55:22 INFO - MultiFileLogger online at 20161020 10:55:22 in /builds/slave/test 10:55:22 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 10:55:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:55:22 INFO - {'append_to_log': False, 10:55:22 INFO - 'base_work_dir': '/builds/slave/test', 10:55:22 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:55:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:55:22 INFO - 'branch': 'Mozilla-Beta', 10:55:22 INFO - 'buildbot_json_path': 'buildprops.json', 10:55:22 INFO - 'config_files': ('talos/linux_config.py',), 10:55:22 INFO - 'default_actions': ('clobber', 10:55:22 INFO - 'read-buildbot-config', 10:55:22 INFO - 'download-and-extract', 10:55:22 INFO - 'populate-webroot', 10:55:22 INFO - 'create-virtualenv', 10:55:22 INFO - 'install', 10:55:22 INFO - 'run-tests'), 10:55:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:55:22 INFO - 'download_minidump_stackwalk': True, 10:55:22 INFO - 'download_symbols': 'ondemand', 10:55:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:55:22 INFO - 'tooltool.py': '/tools/tooltool.py', 10:55:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:55:22 INFO - '/tools/misc-python/virtualenv.py')}, 10:55:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:55:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:55:22 INFO - 'installer_path': 'installer.exe', 10:55:22 INFO - 'log_level': 'info', 10:55:22 INFO - 'log_name': 'talos', 10:55:22 INFO - 'log_to_console': True, 10:55:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:55:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:55:22 INFO - 'opt_config_files': (), 10:55:22 INFO - 'pip_index': False, 10:55:22 INFO - 'sps_profile': False, 10:55:22 INFO - 'sps_profile_interval': 0, 10:55:22 INFO - 'suite': 'dromaeojs', 10:55:22 INFO - 'system_bits': '32', 10:55:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:55:22 INFO - 'title': 'talos-linux64-ix-032', 10:55:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:55:22 INFO - 'use_talos_json': True, 10:55:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:55:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:55:22 INFO - 'work_dir': 'build'} 10:55:22 INFO - [mozharness: 2016-10-20 17:55:22.145018Z] Running clobber step. 10:55:22 INFO - Running pre-action listener: _resource_record_pre_action 10:55:22 INFO - Running main action method: clobber 10:55:22 INFO - rmtree: /builds/slave/test/build 10:55:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:55:23 INFO - Running post-action listener: _resource_record_post_action 10:55:23 INFO - [mozharness: 2016-10-20 17:55:23.128254Z] Finished clobber step (success) 10:55:23 INFO - [mozharness: 2016-10-20 17:55:23.128353Z] Running read-buildbot-config step. 10:55:23 INFO - Running pre-action listener: _resource_record_pre_action 10:55:23 INFO - Running main action method: read_buildbot_config 10:55:23 INFO - Using buildbot properties: 10:55:23 INFO - { 10:55:23 INFO - "project": "", 10:55:23 INFO - "product": "firefox", 10:55:23 INFO - "script_repo_revision": "production", 10:55:23 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:55:23 INFO - "repository": "", 10:55:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:55:23 INFO - "buildid": "20161020074150", 10:55:23 INFO - "pgo_build": "True", 10:55:23 INFO - "basedir": "/builds/slave/test", 10:55:23 INFO - "buildnumber": 431, 10:55:23 INFO - "slavename": "talos-linux64-ix-032", 10:55:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:55:23 INFO - "platform": "ubuntu64_hw", 10:55:23 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:55:23 INFO - "branch": "mozilla-beta", 10:55:23 INFO - "repo_path": "releases/mozilla-beta", 10:55:23 INFO - "revision": "69c68bce430d468037bc82223acb9ea4155dbc7d", 10:55:23 INFO - "stage_platform": "linux64-pgo", 10:55:23 INFO - "builduid": "9712897ee2bf48d3b9fcf4e012fff591", 10:55:23 INFO - "slavebuilddir": "test" 10:55:23 INFO - } 10:55:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 10:55:23 INFO - Running post-action listener: _resource_record_post_action 10:55:23 INFO - [mozharness: 2016-10-20 17:55:23.130764Z] Finished read-buildbot-config step (success) 10:55:23 INFO - [mozharness: 2016-10-20 17:55:23.130852Z] Running download-and-extract step. 10:55:23 INFO - Running pre-action listener: _resource_record_pre_action 10:55:23 INFO - Running main action method: download_and_extract 10:55:23 INFO - mkdir: /builds/slave/test/build/tests 10:55:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:55:23 INFO - https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:55:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:55:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:55:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:55:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:55:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:55:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:55:23 INFO - retry: Failed, sleeping 30 seconds before retrying 10:55:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:55:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:55:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:55:53 INFO - retry: Failed, sleeping 60 seconds before retrying 10:56:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:56:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:56:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:56:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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! 10:56:53 INFO - Caught exception: HTTP Error 404: Not Found 10:56:53 INFO - Caught exception: HTTP Error 404: Not Found 10:56:53 INFO - Caught exception: HTTP Error 404: Not Found 10:56:53 INFO - trying https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:56:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:56:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:56:53 INFO - Downloaded 1327 bytes. 10:56:53 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:56:53 INFO - Using the following test package requirements: 10:56:53 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:56:53 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:56:53 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:56:53 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:56:53 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 10:56:53 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:56:53 INFO - u'jsshell-linux-x86_64.zip'], 10:56:53 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:56:53 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:56:53 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:56:53 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:56:53 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 10:56:53 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:56:53 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 10:56:53 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:56:53 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:56:53 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:56:53 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:56:53 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:56: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')]} 10:56:53 INFO - https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:56:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:56:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:56:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:56:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:56:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:56:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:56:53 INFO - retry: Failed, sleeping 30 seconds before retrying 10:57:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:57:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:57:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:57:23 INFO - retry: Failed, sleeping 60 seconds before retrying 10:58:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:58:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:58:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:58:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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! 10:58:23 INFO - Caught exception: HTTP Error 404: Not Found 10:58:23 INFO - Caught exception: HTTP Error 404: Not Found 10:58:23 INFO - Caught exception: HTTP Error 404: Not Found 10:58:23 INFO - trying https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:58:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:58:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:58:24 INFO - Downloaded 21775677 bytes. 10:58:24 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'] 10:58:24 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 10:58:25 INFO - Return code: 0 10:58:25 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 10:58:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:58:25 INFO - https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:58:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:58:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:58:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:58:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:58:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:58:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:58:25 INFO - retry: Failed, sleeping 30 seconds before retrying 10:58:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:58:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:58:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:58:56 INFO - retry: Failed, sleeping 60 seconds before retrying 10:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:59:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:59:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:59:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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! 10:59:56 INFO - Caught exception: HTTP Error 404: Not Found 10:59:56 INFO - Caught exception: HTTP Error 404: Not Found 10:59:56 INFO - Caught exception: HTTP Error 404: Not Found 10:59:56 INFO - trying https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:59:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:59:56 INFO - Downloaded 21775677 bytes. 10:59:56 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'] 10:59:56 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 10:59:58 INFO - Return code: 0 10:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:59:58 INFO - https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:59:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:59:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:59:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:59:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 10:59:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:59:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:59:58 INFO - retry: Failed, sleeping 30 seconds before retrying 11:00:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:00:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 11:00:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:00:28 INFO - retry: Failed, sleeping 60 seconds before retrying 11:01:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:01:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 11:01:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:01:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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! 11:01:28 INFO - Caught exception: HTTP Error 404: Not Found 11:01:28 INFO - Caught exception: HTTP Error 404: Not Found 11:01:28 INFO - Caught exception: HTTP Error 404: Not Found 11:01:28 INFO - trying https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 11:01:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:01:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:01:29 INFO - Downloaded 14413762 bytes. 11:01:29 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'] 11:01:29 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 11:01:29 INFO - Return code: 0 11:01:29 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')]} 11:01:29 INFO - https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:01:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:01:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:01:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:01:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:01:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:01:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:01:29 INFO - retry: Failed, sleeping 30 seconds before retrying 11:01:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:01:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:01:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:01:59 INFO - retry: Failed, sleeping 60 seconds before retrying 11:02:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:02:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:02:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:02:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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! 11:02:59 INFO - Caught exception: HTTP Error 404: Not Found 11:02:59 INFO - Caught exception: HTTP Error 404: Not Found 11:02:59 INFO - Caught exception: HTTP Error 404: Not Found 11:02:59 INFO - trying https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:02:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:02:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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 11:03:00 INFO - Downloaded 56802915 bytes. 11:03:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:03:00 INFO - mkdir: /builds/slave/test/properties 11:03:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:03:00 INFO - Writing to file /builds/slave/test/properties/build_url 11:03:00 INFO - Contents: 11:03:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:03:01 INFO - Running post-action listener: _resource_record_post_action 11:03:01 INFO - Running post-action listener: set_extra_try_arguments 11:03:01 INFO - [mozharness: 2016-10-20 18:03:01.420769Z] Finished download-and-extract step (success) 11:03:01 INFO - [mozharness: 2016-10-20 18:03:01.421002Z] Running populate-webroot step. 11:03:01 INFO - Running pre-action listener: _resource_record_pre_action 11:03:01 INFO - Running main action method: populate_webroot 11:03:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:03:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:03:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:03:01 INFO - '--tppagecycles', 11:03:01 INFO - '7'], 11:03:01 INFO - 'tests': ['tsvgm']}}, 11:03:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:03:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:03:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:03:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:03:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:03:01 INFO - 'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:03:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:03:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:03:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:03:01 INFO - 'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['damp', 'tps']}, 11:03:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:03:01 INFO - 'tests': ['damp', 'tps']}, 11:03:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['dromaeo_dom']}, 11:03:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:03:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['basic_compositor_video']}, 11:03:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:03:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['a11yr', 11:03:01 INFO - 'ts_paint', 11:03:01 INFO - 'tpaint', 11:03:01 INFO - 'sessionrestore', 11:03:01 INFO - 'sessionrestore_no_auto_restore', 11:03:01 INFO - 'tabpaint']}, 11:03:01 INFO - 'other-e10s': {'tests': ['a11yr', 11:03:01 INFO - 'ts_paint', 11:03:01 INFO - 'tpaint', 11:03:01 INFO - 'sessionrestore', 11:03:01 INFO - 'sessionrestore_no_auto_restore', 11:03:01 INFO - 'tabpaint']}, 11:03:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:03:01 INFO - 'ts_paint', 11:03:01 INFO - 'tpaint', 11:03:01 INFO - 'sessionrestore', 11:03:01 INFO - 'sessionrestore_no_auto_restore', 11:03:01 INFO - 'tabpaint']}, 11:03:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:03:01 INFO - 'ts_paint', 11:03:01 INFO - 'tpaint', 11:03:01 INFO - 'sessionrestore', 11:03:01 INFO - 'sessionrestore_no_auto_restore', 11:03:01 INFO - 'tabpaint']}, 11:03:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['a11yr', 11:03:01 INFO - 'ts_paint', 11:03:01 INFO - 'tpaint', 11:03:01 INFO - 'sessionrestore', 11:03:01 INFO - 'sessionrestore_no_auto_restore', 11:03:01 INFO - 'tabpaint']}, 11:03:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['a11yr', 11:03:01 INFO - 'ts_paint', 11:03:01 INFO - 'tpaint', 11:03:01 INFO - 'sessionrestore', 11:03:01 INFO - 'sessionrestore_no_auto_restore', 11:03:01 INFO - 'tabpaint']}, 11:03:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['tsvgx', 11:03:01 INFO - 'tsvgr_opacity', 11:03:01 INFO - 'tart', 11:03:01 INFO - 'tscrollx', 11:03:01 INFO - 'cart']}, 11:03:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:03:01 INFO - 'tsvgr_opacity', 11:03:01 INFO - 'tart', 11:03:01 INFO - 'tscrollx', 11:03:01 INFO - 'cart']}, 11:03:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:03:01 INFO - 'talos_options': ['--disable-e10s'], 11:03:01 INFO - 'tests': ['tp5o']}, 11:03:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:03:01 INFO - 'tests': ['tp5o']}, 11:03:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:03:01 INFO - 'talos_options': ['--disable-e10s', 11:03:01 INFO - '--xperf_path', 11:03:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:03:01 INFO - 'tests': ['tp5n']}, 11:03:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:03:01 INFO - 'talos_options': ['--xperf_path', 11:03:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:03:01 INFO - 'tests': ['tp5n']}}, 11:03:01 INFO - 'talos.zip': {'path': '', 11:03:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:03:01 INFO - Running post-action listener: _resource_record_post_action 11:03:01 INFO - [mozharness: 2016-10-20 18:03:01.439649Z] Finished populate-webroot step (success) 11:03:01 INFO - [mozharness: 2016-10-20 18:03:01.439738Z] Running create-virtualenv step. 11:03:01 INFO - Running pre-action listener: _resource_record_pre_action 11:03:01 INFO - Running main action method: create_virtualenv 11:03:01 INFO - Creating virtualenv /builds/slave/test/build/venv 11:03:01 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 11:03:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:03:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:03:01 INFO - Using real prefix '/usr' 11:03:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:03:02 INFO - Installing distribute.............................................................................................................................................................................................done. 11:03:03 INFO - Installing pip.................done. 11:03:04 INFO - Return code: 0 11:03:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:03:04 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')]} 11:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:04 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')]} 11:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:04 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:04 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 11:03:04 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 11:03:04 INFO - Using env: {'DISPLAY': ':0', 11:03:04 INFO - 'HOME': '/home/cltbld', 11:03:04 INFO - 'LANG': 'en_US.UTF-8', 11:03:04 INFO - 'LANGUAGE': 'en_US:en', 11:03:04 INFO - 'LOGNAME': 'cltbld', 11:03:04 INFO - 'MAIL': '/var/mail/cltbld', 11:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:04 INFO - 'MOZ_NO_REMOTE': '1', 11:03:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:04 INFO - 'NO_EM_RESTART': '1', 11:03:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:04 INFO - 'PWD': '/builds/slave/test', 11:03:04 INFO - 'SHELL': '/bin/bash', 11:03:04 INFO - 'SHLVL': '1', 11:03:04 INFO - 'TERM': 'linux', 11:03:04 INFO - 'TMOUT': '86400', 11:03:04 INFO - 'USER': 'cltbld', 11:03:04 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:04 INFO - '_': '/tools/buildbot/bin/python'} 11:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:04 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 11:03:04 INFO - Cleaning up... 11:03:04 INFO - Return code: 0 11:03:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:03:04 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')]} 11:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:04 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')]} 11:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:04 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:04 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 11:03:04 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 11:03:04 INFO - Using env: {'DISPLAY': ':0', 11:03:04 INFO - 'HOME': '/home/cltbld', 11:03:04 INFO - 'LANG': 'en_US.UTF-8', 11:03:04 INFO - 'LANGUAGE': 'en_US:en', 11:03:04 INFO - 'LOGNAME': 'cltbld', 11:03:04 INFO - 'MAIL': '/var/mail/cltbld', 11:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:04 INFO - 'MOZ_NO_REMOTE': '1', 11:03:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:04 INFO - 'NO_EM_RESTART': '1', 11:03:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:04 INFO - 'PWD': '/builds/slave/test', 11:03:04 INFO - 'SHELL': '/bin/bash', 11:03:04 INFO - 'SHLVL': '1', 11:03:04 INFO - 'TERM': 'linux', 11:03:04 INFO - 'TMOUT': '86400', 11:03:04 INFO - 'USER': 'cltbld', 11:03:04 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:04 INFO - '_': '/tools/buildbot/bin/python'} 11:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:04 INFO - Downloading/unpacking psutil>=3.1.1 11:03:04 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 11:03:04 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 11:03:04 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 11:03:04 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 11:03:04 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 11:03:04 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 11:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:03:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:03:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:03:06 INFO - Installing collected packages: psutil 11:03:06 INFO - Running setup.py install for psutil 11:03:06 INFO - building 'psutil._psutil_linux' extension 11:03:06 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 11:03:06 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 11:03:06 INFO - building 'psutil._psutil_posix' extension 11:03:06 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 11:03:06 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 11:03:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:03:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:03:06 INFO - Successfully installed psutil 11:03:06 INFO - Cleaning up... 11:03:07 INFO - Return code: 0 11:03:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:03: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')]} 11:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03: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')]} 11:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:07 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:07 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 11:03:07 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 11:03:07 INFO - Using env: {'DISPLAY': ':0', 11:03:07 INFO - 'HOME': '/home/cltbld', 11:03:07 INFO - 'LANG': 'en_US.UTF-8', 11:03:07 INFO - 'LANGUAGE': 'en_US:en', 11:03:07 INFO - 'LOGNAME': 'cltbld', 11:03:07 INFO - 'MAIL': '/var/mail/cltbld', 11:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:07 INFO - 'MOZ_NO_REMOTE': '1', 11:03:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:07 INFO - 'NO_EM_RESTART': '1', 11:03:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:07 INFO - 'PWD': '/builds/slave/test', 11:03:07 INFO - 'SHELL': '/bin/bash', 11:03:07 INFO - 'SHLVL': '1', 11:03:07 INFO - 'TERM': 'linux', 11:03:07 INFO - 'TMOUT': '86400', 11:03:07 INFO - 'USER': 'cltbld', 11:03:07 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:07 INFO - '_': '/tools/buildbot/bin/python'} 11:03:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:03:07 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 11:03:07 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 11:03:07 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 11:03:07 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 11:03:07 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 11:03:07 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 11:03:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:03:09 INFO - Installing collected packages: mozsystemmonitor 11:03:09 INFO - Running setup.py install for mozsystemmonitor 11:03:09 INFO - Successfully installed mozsystemmonitor 11:03:09 INFO - Cleaning up... 11:03:09 INFO - Return code: 0 11:03:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:03:09 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')]} 11:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:09 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')]} 11:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:09 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:09 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 11:03:09 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 11:03:09 INFO - Using env: {'DISPLAY': ':0', 11:03:09 INFO - 'HOME': '/home/cltbld', 11:03:09 INFO - 'LANG': 'en_US.UTF-8', 11:03:09 INFO - 'LANGUAGE': 'en_US:en', 11:03:09 INFO - 'LOGNAME': 'cltbld', 11:03:09 INFO - 'MAIL': '/var/mail/cltbld', 11:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:09 INFO - 'MOZ_NO_REMOTE': '1', 11:03:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:09 INFO - 'NO_EM_RESTART': '1', 11:03:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:09 INFO - 'PWD': '/builds/slave/test', 11:03:09 INFO - 'SHELL': '/bin/bash', 11:03:09 INFO - 'SHLVL': '1', 11:03:09 INFO - 'TERM': 'linux', 11:03:09 INFO - 'TMOUT': '86400', 11:03:09 INFO - 'USER': 'cltbld', 11:03:09 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:09 INFO - '_': '/tools/buildbot/bin/python'} 11:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:09 INFO - Downloading/unpacking jsonschema==2.5.1 11:03:09 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 11:03:09 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 11:03:09 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 11:03:09 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 11:03:09 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 11:03:09 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 11:03:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:03:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:03:11 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 11:03:11 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 11:03:11 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 11:03:11 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 11:03:11 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 11:03:11 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 11:03:11 INFO - Downloading functools32-3.2.3-2.tar.gz 11:03:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:03:11 INFO - warning: no files found matching '*.txt' 11:03:11 INFO - no previously-included directories found matching 'build' 11:03:11 INFO - no previously-included directories found matching 'dist' 11:03:11 INFO - no previously-included directories found matching '.git*' 11:03:11 INFO - Installing collected packages: jsonschema, functools32 11:03:11 INFO - Running setup.py install for functools32 11:03:11 INFO - warning: no files found matching '*.txt' 11:03:11 INFO - no previously-included directories found matching 'build' 11:03:11 INFO - no previously-included directories found matching 'dist' 11:03:11 INFO - no previously-included directories found matching '.git*' 11:03:11 INFO - Successfully installed jsonschema functools32 11:03:11 INFO - Cleaning up... 11:03:11 INFO - Return code: 0 11:03:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:03:11 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')]} 11:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:11 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')]} 11:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:11 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:11 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 11:03:11 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 11:03:11 INFO - Using env: {'DISPLAY': ':0', 11:03:11 INFO - 'HOME': '/home/cltbld', 11:03:11 INFO - 'LANG': 'en_US.UTF-8', 11:03:11 INFO - 'LANGUAGE': 'en_US:en', 11:03:11 INFO - 'LOGNAME': 'cltbld', 11:03:11 INFO - 'MAIL': '/var/mail/cltbld', 11:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:11 INFO - 'MOZ_NO_REMOTE': '1', 11:03:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:11 INFO - 'NO_EM_RESTART': '1', 11:03:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:11 INFO - 'PWD': '/builds/slave/test', 11:03:11 INFO - 'SHELL': '/bin/bash', 11:03:11 INFO - 'SHLVL': '1', 11:03:11 INFO - 'TERM': 'linux', 11:03:11 INFO - 'TMOUT': '86400', 11:03:11 INFO - 'USER': 'cltbld', 11:03:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:11 INFO - '_': '/tools/buildbot/bin/python'} 11:03:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:03:12 INFO - Cleaning up... 11:03:12 INFO - Return code: 0 11:03:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:12 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:12 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 11:03:12 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 11:03:12 INFO - Using env: {'DISPLAY': ':0', 11:03:12 INFO - 'HOME': '/home/cltbld', 11:03:12 INFO - 'LANG': 'en_US.UTF-8', 11:03:12 INFO - 'LANGUAGE': 'en_US:en', 11:03:12 INFO - 'LOGNAME': 'cltbld', 11:03:12 INFO - 'MAIL': '/var/mail/cltbld', 11:03:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:12 INFO - 'MOZ_NO_REMOTE': '1', 11:03:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:12 INFO - 'NO_EM_RESTART': '1', 11:03:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:12 INFO - 'PWD': '/builds/slave/test', 11:03:12 INFO - 'SHELL': '/bin/bash', 11:03:12 INFO - 'SHLVL': '1', 11:03:12 INFO - 'TERM': 'linux', 11:03:12 INFO - 'TMOUT': '86400', 11:03:12 INFO - 'USER': 'cltbld', 11:03:12 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:12 INFO - '_': '/tools/buildbot/bin/python'} 11:03:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:12 INFO - Downloading/unpacking blobuploader==1.2.4 11:03:12 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 11:03:12 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 11:03:12 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 11:03:12 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 11:03:12 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 11:03:12 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 11:03:14 INFO - Downloading blobuploader-1.2.4.tar.gz 11:03:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:03:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:03:14 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 11:03:14 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 11:03:14 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 11:03:14 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 11:03:14 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 11:03:14 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 11:03:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:03:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:03:14 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 11:03:14 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 11:03:14 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 11:03:14 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 11:03:14 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 11:03:14 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 11:03:15 INFO - Downloading docopt-0.6.1.tar.gz 11:03:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:03:15 INFO - Installing collected packages: blobuploader, requests, docopt 11:03:15 INFO - Running setup.py install for blobuploader 11:03:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:03:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:03:15 INFO - Running setup.py install for requests 11:03:15 INFO - Running setup.py install for docopt 11:03:15 INFO - Successfully installed blobuploader requests docopt 11:03:15 INFO - Cleaning up... 11:03:15 INFO - Return code: 0 11:03:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:15 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')]} 11:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:15 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')]} 11:03:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:15 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:15 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 11:03:15 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 11:03:15 INFO - Using env: {'DISPLAY': ':0', 11:03:15 INFO - 'HOME': '/home/cltbld', 11:03:15 INFO - 'LANG': 'en_US.UTF-8', 11:03:15 INFO - 'LANGUAGE': 'en_US:en', 11:03:15 INFO - 'LOGNAME': 'cltbld', 11:03:15 INFO - 'MAIL': '/var/mail/cltbld', 11:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:15 INFO - 'MOZ_NO_REMOTE': '1', 11:03:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:15 INFO - 'NO_EM_RESTART': '1', 11:03:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:15 INFO - 'PWD': '/builds/slave/test', 11:03:15 INFO - 'SHELL': '/bin/bash', 11:03:15 INFO - 'SHLVL': '1', 11:03:15 INFO - 'TERM': 'linux', 11:03:15 INFO - 'TMOUT': '86400', 11:03:15 INFO - 'USER': 'cltbld', 11:03:15 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:15 INFO - '_': '/tools/buildbot/bin/python'} 11:03:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:03:15 INFO - Running setup.py (path:/tmp/pip-ieSFvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:03:16 INFO - Running setup.py (path:/tmp/pip-HYVqsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:03:16 INFO - Running setup.py (path:/tmp/pip-bGUWmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:03:16 INFO - Running setup.py (path:/tmp/pip-q_jU2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:03:16 INFO - Running setup.py (path:/tmp/pip-PX8tBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:03:16 INFO - Running setup.py (path:/tmp/pip-D7olLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:03:16 INFO - Running setup.py (path:/tmp/pip-shDvcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:03:16 INFO - Running setup.py (path:/tmp/pip-Y9cF8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:03:16 INFO - Running setup.py (path:/tmp/pip-RZq9ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:03:16 INFO - Running setup.py (path:/tmp/pip-mX6euy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:03:16 INFO - Running setup.py (path:/tmp/pip-BoxU0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:03:16 INFO - Running setup.py (path:/tmp/pip-icQxmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:03:16 INFO - Running setup.py (path:/tmp/pip-sXD_7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:03:16 INFO - Running setup.py (path:/tmp/pip-bSRzQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:16 INFO - Running setup.py (path:/tmp/pip-YrMkWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:03:16 INFO - Running setup.py (path:/tmp/pip-7MEzAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:03:17 INFO - Running setup.py (path:/tmp/pip-XboxLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:03:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:03:17 INFO - Running setup.py install for manifestparser 11:03:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:03:17 INFO - Running setup.py install for mozcrash 11:03:17 INFO - Running setup.py install for mozdebug 11:03:17 INFO - Running setup.py install for mozdevice 11:03:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:03:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:03:17 INFO - Running setup.py install for mozfile 11:03:17 INFO - Running setup.py install for mozhttpd 11:03:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:03:17 INFO - Running setup.py install for mozinfo 11:03:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:03:17 INFO - Running setup.py install for mozInstall 11:03:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:03:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:03:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:03:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:03:17 INFO - Running setup.py install for mozleak 11:03:18 INFO - Running setup.py install for mozlog 11:03:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:03:18 INFO - Running setup.py install for moznetwork 11:03:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:03:18 INFO - Running setup.py install for mozprocess 11:03:18 INFO - Running setup.py install for mozprofile 11:03:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:03:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:03:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:03:18 INFO - Running setup.py install for mozrunner 11:03:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:03:18 INFO - Running setup.py install for mozscreenshot 11:03:18 INFO - Running setup.py install for moztest 11:03:18 INFO - Running setup.py install for mozversion 11:03:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:03:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:03:18 INFO - Cleaning up... 11:03:18 INFO - Return code: 0 11:03:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:18 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')]} 11:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:18 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')]} 11:03:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:18 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:18 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 11:03:18 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 11:03:18 INFO - Using env: {'DISPLAY': ':0', 11:03:18 INFO - 'HOME': '/home/cltbld', 11:03:18 INFO - 'LANG': 'en_US.UTF-8', 11:03:18 INFO - 'LANGUAGE': 'en_US:en', 11:03:18 INFO - 'LOGNAME': 'cltbld', 11:03:18 INFO - 'MAIL': '/var/mail/cltbld', 11:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:18 INFO - 'MOZ_NO_REMOTE': '1', 11:03:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:18 INFO - 'NO_EM_RESTART': '1', 11:03:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:18 INFO - 'PWD': '/builds/slave/test', 11:03:18 INFO - 'SHELL': '/bin/bash', 11:03:18 INFO - 'SHLVL': '1', 11:03:18 INFO - 'TERM': 'linux', 11:03:18 INFO - 'TMOUT': '86400', 11:03:18 INFO - 'USER': 'cltbld', 11:03:18 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:18 INFO - '_': '/tools/buildbot/bin/python'} 11:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:03:19 INFO - Running setup.py (path:/tmp/pip-IdB57g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:03:19 INFO - Running setup.py (path:/tmp/pip-WuDMvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:03:19 INFO - Running setup.py (path:/tmp/pip-IlvIGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:03:19 INFO - Running setup.py (path:/tmp/pip-41CQ5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:03:19 INFO - Running setup.py (path:/tmp/pip-rAu4YN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:03:19 INFO - Running setup.py (path:/tmp/pip-so_NwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:03:19 INFO - Running setup.py (path:/tmp/pip-Y4fgB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:03:19 INFO - Running setup.py (path:/tmp/pip-TefBY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:03:19 INFO - Running setup.py (path:/tmp/pip-S3_L2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:03:19 INFO - Running setup.py (path:/tmp/pip-yBI_UD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:03:19 INFO - Running setup.py (path:/tmp/pip-osxNaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:03:19 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)) 11:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:03:19 INFO - Running setup.py (path:/tmp/pip-dnTRqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:03:20 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)) 11:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:03:20 INFO - Running setup.py (path:/tmp/pip-TEOPeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:03:20 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)) 11:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:03:20 INFO - Running setup.py (path:/tmp/pip-EWpkCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:03:20 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)) 11:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:20 INFO - Running setup.py (path:/tmp/pip-Rjxgi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:20 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)) 11:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:03:20 INFO - Running setup.py (path:/tmp/pip-wUH5YK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:03:20 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)) 11:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:03:20 INFO - Running setup.py (path:/tmp/pip-PGMseW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:03:20 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)) 11:03:20 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)) 11:03:20 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)) 11:03:20 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)) 11:03:20 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)) 11:03:20 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)) 11:03:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:03:20 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 11:03:20 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 11:03:20 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 11:03:20 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 11:03:20 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 11:03:20 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 11:03:22 INFO - Downloading blessings-1.6.tar.gz 11:03:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:03:22 INFO - Installing collected packages: blessings 11:03:22 INFO - Running setup.py install for blessings 11:03:22 INFO - Successfully installed blessings 11:03:22 INFO - Cleaning up... 11:03:22 INFO - Return code: 0 11:03:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:03:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:03:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:03:22 INFO - Reading from file tmpfile_stdout 11:03:22 INFO - Current package versions: 11:03:22 INFO - argparse == 1.2.1 11:03:22 INFO - blessings == 1.6 11:03:22 INFO - blobuploader == 1.2.4 11:03:22 INFO - docopt == 0.6.1 11:03:22 INFO - functools32 == 3.2.3-2 11:03:22 INFO - jsonschema == 2.5.1 11:03:22 INFO - manifestparser == 1.1 11:03:22 INFO - mozInstall == 1.12 11:03:22 INFO - mozcrash == 0.17 11:03:22 INFO - mozdebug == 0.1 11:03:22 INFO - mozdevice == 0.48 11:03:22 INFO - mozfile == 1.2 11:03:22 INFO - mozhttpd == 0.7 11:03:22 INFO - mozinfo == 0.9 11:03:22 INFO - mozleak == 0.1 11:03:22 INFO - mozlog == 3.3 11:03:22 INFO - moznetwork == 0.27 11:03:22 INFO - mozprocess == 0.23 11:03:22 INFO - mozprofile == 0.28 11:03:22 INFO - mozrunner == 6.12 11:03:22 INFO - mozscreenshot == 0.1 11:03:22 INFO - mozsystemmonitor == 0.3 11:03:22 INFO - moztest == 0.7 11:03:22 INFO - mozversion == 1.4 11:03:22 INFO - psutil == 3.1.1 11:03:22 INFO - requests == 1.2.3 11:03:22 INFO - wsgiref == 0.1.2 11:03:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:22 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')]} 11:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:22 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')]} 11:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:22 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:22 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 11:03:22 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 11:03:22 INFO - Using env: {'DISPLAY': ':0', 11:03:22 INFO - 'HOME': '/home/cltbld', 11:03:22 INFO - 'LANG': 'en_US.UTF-8', 11:03:22 INFO - 'LANGUAGE': 'en_US:en', 11:03:22 INFO - 'LOGNAME': 'cltbld', 11:03:22 INFO - 'MAIL': '/var/mail/cltbld', 11:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:22 INFO - 'MOZ_NO_REMOTE': '1', 11:03:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:22 INFO - 'NO_EM_RESTART': '1', 11:03:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:22 INFO - 'PWD': '/builds/slave/test', 11:03:22 INFO - 'SHELL': '/bin/bash', 11:03:22 INFO - 'SHLVL': '1', 11:03:22 INFO - 'TERM': 'linux', 11:03:22 INFO - 'TMOUT': '86400', 11:03:22 INFO - 'USER': 'cltbld', 11:03:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:22 INFO - '_': '/tools/buildbot/bin/python'} 11:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:22 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)) 11:03:22 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)) 11:03:22 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)) 11:03:22 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)) 11:03:22 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)) 11:03:22 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)) 11:03:22 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)) 11:03:22 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)) 11:03:22 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)) 11:03:22 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)) 11:03:22 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)) 11:03:22 INFO - Cleaning up... 11:03:23 INFO - Return code: 0 11:03:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:03:23 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')]} 11:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:23 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')]} 11:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:03:23 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 0xd89030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, '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': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', '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 11:03:23 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 11:03:23 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 11:03:23 INFO - Using env: {'DISPLAY': ':0', 11:03:23 INFO - 'HOME': '/home/cltbld', 11:03:23 INFO - 'LANG': 'en_US.UTF-8', 11:03:23 INFO - 'LANGUAGE': 'en_US:en', 11:03:23 INFO - 'LOGNAME': 'cltbld', 11:03:23 INFO - 'MAIL': '/var/mail/cltbld', 11:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:23 INFO - 'MOZ_NO_REMOTE': '1', 11:03:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:23 INFO - 'NO_EM_RESTART': '1', 11:03:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:23 INFO - 'PWD': '/builds/slave/test', 11:03:23 INFO - 'SHELL': '/bin/bash', 11:03:23 INFO - 'SHLVL': '1', 11:03:23 INFO - 'TERM': 'linux', 11:03:23 INFO - 'TMOUT': '86400', 11:03:23 INFO - 'USER': 'cltbld', 11:03:23 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:23 INFO - '_': '/tools/buildbot/bin/python'} 11:03:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:03:23 INFO - Cleaning up... 11:03:23 INFO - Return code: 0 11:03:23 INFO - Running post-action listener: _resource_record_post_action 11:03:23 INFO - Running post-action listener: _start_resource_monitoring 11:03:23 INFO - Starting resource monitoring. 11:03:23 INFO - [mozharness: 2016-10-20 18:03:23.249256Z] Finished create-virtualenv step (success) 11:03:23 INFO - [mozharness: 2016-10-20 18:03:23.249775Z] Running install step. 11:03:23 INFO - Running pre-action listener: _resource_record_pre_action 11:03:23 INFO - Running main action method: install 11:03:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:03:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:03:23 INFO - Reading from file tmpfile_stdout 11:03:23 INFO - Detecting whether we're running mozinstall >=1.0... 11:03:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:03:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:03:23 INFO - Reading from file tmpfile_stdout 11:03:23 INFO - Output received: 11:03:23 INFO - Usage: mozinstall [options] installer 11:03:23 INFO - Options: 11:03:23 INFO - -h, --help show this help message and exit 11:03:23 INFO - -d DEST, --destination=DEST 11:03:23 INFO - Directory to install application into. [default: 11:03:23 INFO - "/builds/slave/test"] 11:03:23 INFO - --app=APP Application being installed. [default: firefox] 11:03:23 INFO - mkdir: /builds/slave/test/build/application 11:03:23 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'] 11:03:23 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 11:03:38 INFO - Reading from file tmpfile_stdout 11:03:38 INFO - Output received: 11:03:38 INFO - /builds/slave/test/build/application/firefox/firefox 11:03:38 INFO - Running post-action listener: _resource_record_post_action 11:03:38 INFO - [mozharness: 2016-10-20 18:03:38.792104Z] Finished install step (success) 11:03:38 INFO - [mozharness: 2016-10-20 18:03:38.792356Z] Running run-tests step. 11:03:38 INFO - Running pre-action listener: _resource_record_pre_action 11:03:38 INFO - Running main action method: run_tests 11:03:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:03:38 INFO - Python 2.7.3 11:03:38 INFO - Return code: 0 11:03:38 INFO - grabbing minidump binary from tooltool 11:03:38 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')]} 11:03:38 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 0x10483b0>, '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 0x10492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1049740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:03:38 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 11:03:38 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 11:03:38 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 11:03:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:03:38 INFO - Return code: 0 11:03:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:03:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:03:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:03:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:03:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:03:38 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/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 11:03:38 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/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 11:03:38 INFO - Using env: {'DISPLAY': ':0', 11:03:38 INFO - 'HOME': '/home/cltbld', 11:03:38 INFO - 'LANG': 'en_US.UTF-8', 11:03:38 INFO - 'LANGUAGE': 'en_US:en', 11:03:38 INFO - 'LOGNAME': 'cltbld', 11:03:38 INFO - 'MAIL': '/var/mail/cltbld', 11:03:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:03:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:38 INFO - 'MOZ_NO_REMOTE': '1', 11:03:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:03:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:38 INFO - 'NO_EM_RESTART': '1', 11:03:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:38 INFO - 'PWD': '/builds/slave/test', 11:03:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:03:38 INFO - 'SHELL': '/bin/bash', 11:03:38 INFO - 'SHLVL': '1', 11:03:38 INFO - 'TERM': 'linux', 11:03:38 INFO - 'TMOUT': '86400', 11:03:38 INFO - 'USER': 'cltbld', 11:03:38 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1476986118.141679-1378209174', 11:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:38 INFO - '_': '/tools/buildbot/bin/python'} 11:03:38 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/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 11:03:39 INFO - mozversion application_buildid: 20161020074150 11:03:39 INFO - mozversion application_changeset: 69c68bce430d468037bc82223acb9ea4155dbc7d 11:03:39 INFO - mozversion application_display_name: Firefox 11:03:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:03:39 INFO - mozversion application_name: Firefox 11:03:39 INFO - mozversion application_remotingname: firefox 11:03:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:03:39 INFO - mozversion application_vendor: Mozilla 11:03:39 INFO - mozversion application_version: 50.0 11:03:39 INFO - mozversion platform_buildid: 20161020074150 11:03:39 INFO - mozversion platform_changeset: 69c68bce430d468037bc82223acb9ea4155dbc7d 11:03:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:03:39 INFO - mozversion platform_version: 50.0 11:03:39 INFO - using testdate: 1476986619 11:03:39 INFO - actual date: 1476986619 11:03:39 INFO - starting webserver on 'localhost:45127' 11:03:39 INFO - SUITE-START | Running 2 tests 11:03:39 INFO - TEST-START | dromaeo_css 11:03:39 INFO - operating with platform_type : linux_ 11:03:39 INFO - Initialising browser for dromaeo_css test... 11:03:39 INFO - TEST-INFO | started process 14620 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd6FIXG/profile http://localhost:45127/getInfo.html) 11:03:43 INFO - PROCESS | 14620 | __metrics Screen width/height:1600/1200 11:03:43 INFO - PROCESS | 14620 | colorDepth:24 11:03:43 INFO - PROCESS | 14620 | Browser inner width/height: 1024/697 11:03:43 INFO - PROCESS | 14620 | __metrics 11:03:44 INFO - PROCESS | 14620 | 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" 11:03:44 INFO - PROCESS | 14620 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:03:45 INFO - PROCESS | 14620 | console.error: PushService: 11:03:45 INFO - PROCESS | 14620 | stateChangeProcessEnqueue: Error transitioning state 11:03:45 INFO - PROCESS | 14620 | UnknownError 11:03:45 INFO - TEST-INFO | 14620: exit 0 11:03:45 INFO - Browser initialized. 11:03:45 INFO - Running cycle 1/1 for dromaeo_css test... 11:03:45 INFO - TEST-INFO | started process 14708 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd6FIXG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:03:57 INFO - PROCESS | 14708 | RSS: Main: 143863808 11:03:57 INFO - PROCESS | 14708 | 11:04:48 INFO - PROCESS | 14708 | 1476986688852 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] 11:06:57 INFO - PROCESS | 14708 | Cycle 1(1): loaded http://localhost:45127/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45127/tests/dromaeo/cssquery-ext.html) 11:06:58 INFO - PROCESS | 14708 | RSS: Main: 260890624 11:06:58 INFO - PROCESS | 14708 | 11:10:00 INFO - PROCESS | 14708 | Cycle 1(1): loaded http://localhost:45127/tests/dromaeo/cssquery-ext.html (next: http://localhost:45127/tests/dromaeo/cssquery-jquery.html) 11:10:01 INFO - PROCESS | 14708 | RSS: Main: 236257280 11:10:01 INFO - PROCESS | 14708 | 11:13:03 INFO - PROCESS | 14708 | Cycle 1(1): loaded http://localhost:45127/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45127/tests/dromaeo/cssquery-mootools.html) 11:13:03 INFO - PROCESS | 14708 | RSS: Main: 177467392 11:13:03 INFO - PROCESS | 14708 | 11:16:05 INFO - PROCESS | 14708 | Cycle 1(1): loaded http://localhost:45127/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45127/tests/dromaeo/cssquery-prototype.html) 11:16:06 INFO - PROCESS | 14708 | RSS: Main: 227000320 11:16:06 INFO - PROCESS | 14708 | 11:19:08 INFO - PROCESS | 14708 | Cycle 1(1): loaded http://localhost:45127/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45127/tests/dromaeo/cssquery-yui.html) 11:19:09 INFO - PROCESS | 14708 | RSS: Main: 178507776 11:19:09 INFO - PROCESS | 14708 | 11:22:11 INFO - PROCESS | 14708 | Cycle 1(1): loaded http://localhost:45127/tests/dromaeo/cssquery-yui.html (next: http://localhost:45127/tests/dromaeo/cssquery-dojo.html) 11:22:12 INFO - PROCESS | 14708 | RSS: Main: 262049792 11:22:12 INFO - PROCESS | 14708 | 11:22:12 INFO - PROCESS | 14708 | __start_tp_report 11:22:12 INFO - PROCESS | 14708 | _x_x_mozilla_page_load 11:22:12 INFO - PROCESS | 14708 | _x_x_mozilla_page_load_details 11:22:12 INFO - PROCESS | 14708 | |i|pagename|runs| 11:22:12 INFO - PROCESS | 14708 | |0;dojo.html;3160.952585711214;3168.8257145856983;3169.429502689515;3190.2183964928595;3230.951535726963;5478.150886612571;5571.9721401393035;5593.216949627045;5599.1041433370665;5618.269624948756;5617.101263797793;5681.801136960207;5693.629914055584;5698.6865722385255;5718.914216286748;5109.4635063318465;5142.614303927202;5179.093658609729;5204.557612602934;5212.061828870802;4997.100521906055;5076.898462030757;5211.791528338862;5217.7130257836;5225.163973764198;4989.550940415362;5028.7988480460735;5044.621653375993;5115;5139.357580302462;5127.948720512784;5135.486451354872;5138.3577052868395;5138.845834624967;5172.043172013189;4906.165951788204;5035.320231768701;5047.848564543033;5093.083245015896;5105.336306280197;5510.834674959758;5531.806386776484;5566.693831839248;5624.91562626561;5662.405447428002;6022.3074346450285;6050.939490605082;6071.1803906472505;6071.423214794588;6078.209832721762;5858.912116318259;6095.6952152392205;6096.024636058254;6097.298810636789;6100.328963813977;5603.691796951166;5681.460261275172;5686.033374326333;5753.510951569122;5765.452282033284;6272.247330320391;6276.372362763778;6282.340354262737;6283.59156654816;6288.717007734572;5727.5990680652785;5970.9701451492465;6010.789622363283;6024.4879185268865;6030.819075427744;5890.440408161217;5894.056950887837;5935.0503919372695;5992.0712289595185;6044.879102418015;5925.496332811759;5983.371745966698;5988.490978266809;5990.221271234712;5992.760289588367;5898.557608179403;5972.731227094791;5980.611260268069;5985.910211346833;5995;4531.071130418256;4536.160810250049;4544.522825103317;4581.839635612738;4612.86161415158;4082.9795851020554;4083.3262511685834;4086.9795651021554;4087.2234275487563;4114.979425102855;5704.030314846486;5763.394843541451;5767.163761254678;5767.250257466587;5808.419158084243;5892.145638882338;5919.437653422918;5930.584859059911;5942.3166335871065;6038.63768173911;5697.116946873241;5697.743601537941;5702.315722113373;5715.857103572361;5720.713964301768;5130.076586214443;5145.3568303962;5161.380634323905;5175.482451754794;5194.818181363634;6082.057281121433;6089.208402907594;6096.786612468542;6110.114033465123;6189.96905015481;5988.850278742892;5990.64056156632;5992.191054207626;5992.67040312787;6025.578209525291;6145.262568491631;6177.413145751147;6185.134081228722;6185.783497577563;6188.9690551546955;5797.043487824461;5862.794802181903;5880.8235752927485;5883;6003.489703375088;6022;6042.5770196087615;6046.879062418639;6070.575059745775;6072.574919755752;5967.7016149191495;5997.10043493462;5999;6001.5798894077;6005.3694362091355;5785.045467497984;5823.592348535732;5844.035734103825;5873.853153671193;5971.193888824953;5932.466078052996;5940.762369505172;5941.881162376815;5947.6431414115295;5950.940490595039;5979.312379076462;5981.760729570942;5984.341722410444;5985.341612422543;5990.9700451497465;5611.607187496836;5707.400722924199;5714.342850572238;5721.628094173865;5853 11:22:12 INFO - PROCESS | 14708 | |1;ext.html;589.9734511946962;653.8659574787168;654.6857508395968;674.5514233035032;675.3111825937547;34525.65474345256;46429.30356044662;46906.53093469064;47017.52982470174;47120.29319560209;47496.287555686606;48050;48128;48166.75916620421;48172;8407.201315874981;9295.256379489625;9442.150206481416;9445.29160312976;9457.716268511922;10889.455527223647;11255.4372281386;11295.435228238597;11671.41642917855;11680.065594752441;11395.943020284887;11517.65447036586;11518.251313664627;11524.19330646855;11526.596569120082;11271.323720576791;11300.434978251136;11335.489902954387;11349.886501135048;11356.48895799691;11149.498272577754;11239.157063220291;11247.55009799607;11247.943760281147;11248.043916267126;45807.54192458066;45866.31200531961;45884.311735323994;46319.768401158115;46342;3771.9622803771886;3820.1595648957186;3864.3044252034624;3998.980005099956;4446.777661116931;34777.304453911034;35130.82434587836;35137.64862351369;35208.64791352079;35572;4757.096151731194;4793;4794.832180873687;4834.105690447279;4856.465788763233;396707;402018;402233.9888300569;403422;403715;64994;65562;65579.67210163918;65721;65868.34131658718;38787.612123878964;40666.39000414996;40742.185156296706;43094;67067.66466167735;17508.912455437643;17573.03348315855;17577.033263170393;17584.12079396025;17614.91192544055;11941.64175074749;13414.061015729001;14075.014748967475;14076.648083797782;14129.646758831112;21078.367648970554;21351.786482135292;21357;21499.355019349445;21589.78410215909;7549.3583045440655;7774.766756997299;7797.883031754528;7908.248716371935;8079.272865442021;1054.7415883108688;1061.7717190804015;1082.4154956323498;1112.9499172537255;1115.6697258682825;35101.47347789785;35292;35302;35481.64518354835;35555;1169.3393232823428;1187.3825610682397;1191.4221602522769;1196.9222000569935;1223.0887988448662;18179.727304090447;18709.25162993465;18735.437936861916;18866;18928.71606925897;608537;613917;614472;614541;616197;64843;65395;65528.344716553176;65568.67215663892;65636;1323.860994595573;1374.29223949666;1405.3184205660248;1445.7542217822995;1481.0669278354567;31548.369032619677;36403.817980909924;36578.634213657526;36657.26685466329;36790.26419471649;55765.72117139388;56090;56309.43690563206;56434.43565564292;56475;418153;419199;419523;422344;422624;31035.379292414476;31798.205044874063;31810.204744881565;31828.204294891886;31830;53180;54209.18686219631;55211;55372.44627553673;55709;44109.33835992398;45721.77139114283;46007.30989035234;46257;47032.29451558298;45027.549724503646;45747.31379029251;46061;46538.76730616325;46967.530324696316;44351.77824110859;44759.55240447554;45102.77448612736;46069.30896036495;46204;38575;39886.800565996986;40345.798271008454;40718.59281407267;41123.7943810279;30943.845280773454;31389.802713809815;31403.68596314099;31426.52860207053;31499 11:22:12 INFO - PROCESS | 14708 | |2;jquery.html;421.55104813373777;427.0809020345935;435.16231254834446;439.8416570034787;441.7394232626318;19179.32872349468;20452.795472045276;20977.056032478537;21258.46853828655;21525.461863453384;19401.708974365396;19426.51433714158;21249.468763280925;21464.141434342648;21818.236361727344;5305.495977882105;5532.446755324476;5554.91667624986;5559.499645031946;5560.666360018392;5216.165413533835;5315.7873685052555;5342.893142137155;5346.03771321162;5510.559155267569;5290.497402746733;5303.204519322094;5340.145576707728;5342.545883599899;5366.973165134188;5303.681779093247;5364.6512976656395;5495.890082198353;5511.035568775448;5527;5354.089804733205;5364.758585863646;5366.812161574326;5367.838964831022;5369.301990741179;14363.784543231859;14448.566543003726;14467.059641123293;14527.85472145286;14551.126932384092;2490.203134996802;2545.2745967399387;2572.9099481518056;2644.8148629595935;2760.1719484154673;10844;10904.236703430763;11099.44502774858;11300.773984520345;11441.599544015919;1956.8238858502802;2008.1866843928233;2050.4361300642295;2084.6247675418417;2183.705199798023;61141;69248;70323.64838175777;84625;89888.5505572468;11719.648410547697;11866.110041746904;11933.940330298294;11961.820572691417;11962.40187990597;14626.195559244132;14925.850741492663;15414.537563873102;15470.690586188439;16075.115868627337;6706.195256569145;6868.484863635247;7965.880511792212;8240.752777416687;8482.872756908651;7837.7648670539975;7860.292573668397;7878.409119316074;8021.358291336679;8031.638576264082;4985.750712464363;5039.26930595069;5043.394792624835;5136.897262054738;5150.381954165524;2071.906764195615;2139.6041732279577;2141.764405915348;2175.9020844062056;2218.966715499269;984.6159997600942;990.5988074829561;1000.8298589239847;1015.5379302417382;1049.7795462952713;18445.354412595494;19973.300934467516;20128.39614811558;20901.581968360548;21247;1042.3120740311358;1054.8681414823147;1056.0442799266675;1096.8245080787035;1115.4255558387438;11015.83476247857;11334.69281672959;11670.76658466826;11714.648560543197;11750.529978800752;485720;492060;534272;541517;548523;87798;89872.5506372464;92025.53987230021;103283.48358258161;103833;1075.736444571085;1079.1312993040679;1097.5170924793065;1115.7991561518843;1123.1412013118302;18354.541136471697;18659.90670046641;18840.717389239446;18853.90573047126;19031.52421189481;41978.79010604927;42552.361714573686;43839.56160438355;43940.78029609959;44245;55558;56747.432525674216;58421;58576.707116464146;64705.35294646993;8831.425957312755;8953.462792232227;9002.594883230402;9100.863487047567;9379.155875971195;9886.060824221688;13254.138480998705;13452.529161479693;13615.387307571382;13721;11524.250923689933;11872.940635296769;12001.219920705125;12067.396630168632;12091.516339346328;10771.784564308513;11328.886711132784;11465.713357165803;11796.115291353011;11895.167338286583;11822.645320640395;11859;12182.6954326139;12348;12549.247045177319;8592.484450932963;8733.56332183401;8741.34439916996;8825.514596697243;9257.444553326823;10897.291676041032;11268.492917818881;11828.46771895284;11916.225445346025;12226.694332641755 11:22:12 INFO - PROCESS | 14708 | |3;mootools.html;1419.8722115009648;1555.3778488604557;1582.050769538277;1780.2522940365045;1898.3735367328784;9101.226395756361;9609.135177833994;9612.855807162898;9884.110430061293;9885.35745176563;9817.410955342666;9949.253805964563;9951.452670103141;9952.900470995288;9955.253355998293;2465.1618449727043;2491.3397949543387;2491.8878650460683;2492.8130390220717;2494.9875250623722;2411.2766170148884;2493.738157493455;2497.987510062447;2502.762237587426;2503.086373473678;2434.3183908505557;2434.732179460258;2454.6563481112657;2461.212412028152;2462.704475462947;2319.9652005219937;2350.247920665388;2377.2511658827416;2392.2225276785116;2401.7238017628024;2380.27401642499;2385.1890357278608;2387.224152150558;2389.175734371639;2389.2951579284154;9217.216536594398;9832.115109640166;9864.852027219598;9931.950340248324;9948.90051099487;5413.350397952231;5623.634463759883;5631.8028868989795;5633.915491267634;5634.971825140848;3463.9826800866085;3524.488949102366;3546.414841551141;3585.2470981094;3595.2449985503076;1185.82805493203;1195.7433918790764;1225.5526732742485;1227.4476485581524;2278.065992942902;13413.66465838352;14030.228337441436;14047;14067.648308792259;14100.294985250799;4726.527347265314;4752.334673145762;4825.806967721322;4857.927131093036;4939.2838038484215;11073.889261107446;11144.387058711849;11157.72105697364;11171.106311495061;11184.328940263611;5597.328320601554;5608.6073974821375;5609.859753506195;5611.270534830446;5613.298337707786;4300.634446072119;4584.908301833944;5175.534201921845;5218.399884013387;5261.394939581921;4172.395002724588;4188.694389805214;4189.172308691621;4205.852795152155;4227.281362168439;1223.5044806853239;1252.9976019184799;1258.5783762439685;1260.5273022616518;1279.5905310300614;937.6062053937362;942.3639043645512;1046.199657262195;1054.2936232724094;1059.7827445373835;6252.562320637603;6280.434760871543;6341.397567231105;6365.58623689468;6609.206895172514;474.0542617478153;477.1721063954037;478.89943111946883;484.6074679509608;487.349388566268;4191.287481128215;4372.825086996484;4442.022754993896;4448.621867141329;4452.866414007585;12037.277763334228;12313.322767247888;12430.37848107591;12436.316002619942;12472.189307694971;11733.237339572901;12190.878091218974;12215.633530994084;12355.93822030884;12665.050121240944;8868.955655221682;9049.411788233743;9065.501397423073;9098.772530686654;9102.362834601514;6310.558260921692;6561.1470508833545;6580.144581204413;6592.044153597797;6664.466842652673;11361.431928403712;11528.365939873387;11542.538298467967;11630.476628551905;11634.6509604712;9415.858762118436;10388.48057597102;11822.231554948901;11870.406479675808;11948;8012.83974320522;8279.254867061993;8597.570121493774;8796.60415281301;8805.339599530189;9369;9453.101955314234;9458.243340532603;9478.431294122374;9715;8601.655933762579;8675.783105422415;8678.78303042429;8778.956105219433;8856.380053396459;8504;8538.402311838112;8588.527630980107;8691.435056721293;8777.51723655205;7510.511816731895;7598.582077985543;7603.581803000667;7604.885926711215;7632.313091821763;5435.728213589226;5447.945520544902;5551.2228288040515;5573.581981351496;5622.6064175508955;5130.820421285237;5181.766820493162;5203.16749320107;5241;5335.839924802262 11:22:12 INFO - PROCESS | 14708 | |4;prototype.html;354.886436340371;359.4805506043767;360.5817251987694;365.36244253777164;369.51962448816533;5557.360903496099;5599.524040456566;5618.185363122345;5636.985342638323;5641.379448260688;5279.392869819972;5315.681059136455;5412;5501.807436739715;5504.752286147123;4354.368616550598;4368.978155109236;4384.123175364932;4385.144896745136;4406.140802543506;4285.57144285572;4404.515503294635;4412.40432541606;4436.622887054604;4438.378626992224;4346.9565304347025;4352.608265256126;4369.497507786597;4369.541198174194;4390.890227744316;4352.564743525654;4352.912941741163;4363.629091527224;4373.7813109344725;4392.868213953587;3855.306044911915;3879.5538513071074;3895.668868146183;3925.568187499373;3948.960510394888;5201.323827902386;5310.2565640809935;5314.707691076989;5324.444266751942;5443.455654434564;4998.925016124761;5000.599952003831;5006.299118123466;5016.272640467112;5033.3205017322925;5737.799177028784;5738.282714660668;5748.7125643717645;5751.654900705971;5780.710964451802;3971.6425521702895;4145.585441455861;4178.749275043477;4186.267403204458;4192.790360481994;5919.230500035054;5944.554158438091;5971.552133589955;6001.5198784097165;6020.909686354708;4331.45856767904;4352.194843953878;4364.127174565099;4368.5194628590825;4386.210482113186;4155.3143731284;4312.525622181557;4361.80371883266;4378.868633940987;4380.8685739427865;4943.555080042814;4974.905520785422;4978.626603004789;4989.925151122736;5006.674566153261;5651.208830763697;5782.624129431658;5799.652020878761;5822.82531524055;5852.707364631837;1440.1431148466645;1463.3853781411833;1467.23703674091;1469.1478942213491;1471.529110684592;981.9165370943407;992.2012779712256;1008.9899101008896;1019.8266294729914;1025.415513157493;5626.774929002794;5637.49262566371;5665.093585026376;5676.083502383342;5688.829335119953;5703.771849126071;5893.233879595625;5912.526997840162;5913.201717768132;5974.163617093611;5539.41836107211;5570.303712035996;5589.916151257735;5600.4959553640365;5621.494065534122;3081.9083703076017;3095.2416657918952;3120.018788069592;3132.201288671374;3157.4790159623863;5888.440598143169;5985.850353741104;6008.309044459856;6009.338972712998;6012.368701286389;5873.383294754075;5897.761470091329;5923.318818335946;5953.3451320354725;5957.195778569924;5720.85582883425;5963.5527335450015;6022.879542409215;6024.969875150597;6082.330943596201;5792.4497172768515;5832.591718579659;5841.678707671119;5843.328017278068;5858.5898987070495;5576.466471720147;5741;5798.043322851851;5802.5357971361345;5809.535237180931;5987.790427335022;5987.910181347371;6020.54845886569;6049.848753781191;6053.75784968614;4244.893877653083;4252.681048921281;4254.383114448449;4256.084941737541;4264.147170565837;5635.577331700057;5795.507381872588;5893.705314734332;5898.8230353089475;5902.380250073852;5716.514096301861;5717.199592057199;5725.6850873202375;5743.4256574342235;5755.798547051;5733.627314224562;5740.7944331690815;5754.827355179351;5765.91351129725;5792.160136780228;5327.200919861897;5531.612787104864;5706.457886500776;5804.82585522435;5817.272840894888;5846.503047240862;5851.912221316768;5858.970705146447;5859.794907178229;5879.676617786063;5442.673439593637;5685.573581981285;5715.31416230055;5732.914006289992;5742.339630942495 11:22:12 INFO - PROCESS | 14708 | |5;yui.html;805.9717909873156;854.6111519258734;872.1409411729447;926.5089502563644;935.7941252924356;15967.840321596781;17604.38384656537;17628.206730697133;17648.735268970977;17656.646867062653;14243.501477448292;14267.286635668226;14277.714445711079;14312.35594398252;15993.760093598576;758.8102974256436;882.6954700628289;887.0464250930244;902.6930843513204;915.0849150849151;906.188960880012;908.5457271364318;909.1045320359474;909.4452384045727;909.6225066597354;958.1807554540871;959.6928982725531;960.4189465373453;961.4183419031456;961.9855702164473;955.574769227694;957.1385752822446;957.8658987741757;958.7315551645551;958.7459323279337;698.2075344483997;700.726716580534;700.7827573452195;700.7967689370076;700.9719611215596;14861.256937153099;14895.255237238094;14907.70184596302;14939.327730252056;14960.925195374062;15276.520839756166;16131.193440328054;16151.91924040384;16177.272022759003;16624.33502659892;12570.497180112785;12766.80849787254;12958.352082395842;12984;13920.095193812369;4138.544760076389;4173.373993900909;4218.029853133797;4237.639800616951;4238.109996900624;79868;80958;81791;83429;83966.58016709877;12341.876581234073;12363.814542781865;12410.197217855131;12562;12728.6817829555;21419.464513386978;22125.336239912827;22329.330120096423;23134.305970820904;23423.180188693314;9161;9183.494907780003;9533.904660953302;9682.983286754788;9797.510124493747;5213.843584692389;5285.233641122016;5377.865553361197;5388.326459192601;5649.717514124277;3847.3844184930276;3972.2651309507833;3979.224051309967;3982.761034337949;3985.6412922837085;683.255251775567;684.1755684400285;694.4722011271369;696.2793508718452;711.8220444888777;758.0978635423828;801.2828518476048;820.4789958376474;824.8927639406838;858.8969323681198;10702.518386672618;10742.73143171427;10796.730081748019;10990.725231869268;11213.21507494484;646.615263917968;725.8983742276085;737.4284929179821;745.6905384265577;749.6102026945957;4937.481564435682;5316.388615309212;5538.944610553924;5567.164925261162;5657.349404818498;53932;90872;133544;139996.30001849926;140661;111625;115373.42313288548;115537.4223128879;117269.4136529312;117582.41208793901;58977.70511147417;94883.52558237164;100654.4967275159;101654;103643.4817825921;10422.166226701996;10467.110295624652;10482.790344193227;10677.252592318464;10734.248602597743;55420;56769;56890;57496.837673928334;57847.13229301648;49417.258741118196;49612;79599.81085587761;86915;89060;12903.870961290642;13009.21944683322;13431.12697674648;13668.179909205479;13840.307984600931;15329.233538323262;15389.230538473255;15806.76289855676;16303.510894673178;16374.508764737076;14497.710045799236;14704.77942830837;14933.10401375921;15259.23703814827;15508.612284692974;13953.650531181196;14132.50536231227;14730;14853.554393368217;15903.443379481663;12343.318144468585;14478.203698796668;14626.122432653649;14875.256237188314;15166.165860877758;11542.307461552333;12305.93847030759;12828.935855320664;12906.870931290567;13340.399682014138;12181.512739490321;12426.937865310616;12430.627081187578;12484.188527745668;12613.49546018149 11:22:12 INFO - PROCESS | 14708 | __end_tp_report 11:22:12 INFO - PROCESS | 14708 | __start_cc_report 11:22:12 INFO - PROCESS | 14708 | _x_x_mozilla_cycle_collect,1018 11:22:12 INFO - PROCESS | 14708 | __end_cc_report 11:22:12 INFO - PROCESS | 14708 | __startTimestamp1476987732062__endTimestamp 11:22:12 INFO - PROCESS | 14708 | 11:22:12 INFO - PROCESS | 14708 | ------- Summary: start ------- 11:22:12 INFO - PROCESS | 14708 | Number of tests: 6 11:22:12 INFO - PROCESS | 14708 | 11:22:12 INFO - PROCESS | 14708 | [#0] dojo.html Cycles:165 Average:5589.83 Median:5823.59 stddev:648.21 (11.1%) stddev-sans-first:621.56 11:22:12 INFO - PROCESS | 14708 | Values: 3161.0 3168.8 3169.4 3190.2 3231.0 5478.2 5572.0 5593.2 5599.1 5618.3 5617.1 5681.8 5693.6 5698.7 5718.9 5109.5 5142.6 5179.1 5204.6 5212.1 4997.1 5076.9 5211.8 5217.7 5225.2 4989.6 5028.8 5044.6 5115.0 5139.4 5127.9 5135.5 5138.4 5138.8 5172.0 4906.2 5035.3 5047.8 5093.1 5105.3 5510.8 5531.8 5566.7 5624.9 5662.4 6022.3 6050.9 6071.2 6071.4 6078.2 5858.9 6095.7 6096.0 6097.3 6100.3 5603.7 5681.5 5686.0 5753.5 5765.5 6272.2 6276.4 6282.3 6283.6 6288.7 5727.6 5971.0 6010.8 6024.5 6030.8 5890.4 5894.1 5935.1 5992.1 6044.9 5925.5 5983.4 5988.5 5990.2 5992.8 5898.6 5972.7 5980.6 5985.9 5995.0 4531.1 4536.2 4544.5 4581.8 4612.9 4083.0 4083.3 4087.0 4087.2 4115.0 5704.0 5763.4 5767.2 5767.3 5808.4 5892.1 5919.4 5930.6 5942.3 6038.6 5697.1 5697.7 5702.3 5715.9 5720.7 5130.1 5145.4 5161.4 5175.5 5194.8 6082.1 6089.2 6096.8 6110.1 6190.0 5988.9 5990.6 5992.2 5992.7 6025.6 6145.3 6177.4 6185.1 6185.8 6189.0 5797.0 5862.8 5880.8 5883.0 6003.5 6022.0 6042.6 6046.9 6070.6 6072.6 5967.7 5997.1 5999.0 6001.6 6005.4 5785.0 5823.6 5844.0 5873.9 5971.2 5932.5 5940.8 5941.9 5947.6 5950.9 5979.3 5981.8 5984.3 5985.3 5991.0 5611.6 5707.4 5714.3 5721.6 5853.0 11:22:12 INFO - PROCESS | 14708 | 11:22:12 INFO - PROCESS | 14708 | [#1] ext.html Cycles:180 Average:65418.52 Median:402827.99 stddev:129394.74 (32.1%) stddev-sans-first:129666.18 11:22:12 INFO - PROCESS | 14708 | Values: 590.0 653.9 654.7 674.6 675.3 34525.7 46429.3 46906.5 47017.5 47120.3 47496.3 48050.0 48128.0 48166.8 48172.0 8407.2 9295.3 9442.2 9445.3 9457.7 10889.5 11255.4 11295.4 11671.4 11680.1 11395.9 11517.7 11518.3 11524.2 11526.6 11271.3 11300.4 11335.5 11349.9 11356.5 11149.5 11239.2 11247.6 11247.9 11248.0 45807.5 45866.3 45884.3 46319.8 46342.0 3772.0 3820.2 3864.3 3999.0 4446.8 34777.3 35130.8 35137.6 35208.6 35572.0 4757.1 4793.0 4794.8 4834.1 4856.5 396707.0 402018.0 402234.0 403422.0 403715.0 64994.0 65562.0 65579.7 65721.0 65868.3 38787.6 40666.4 40742.2 43094.0 67067.7 17508.9 17573.0 17577.0 17584.1 17614.9 11941.6 13414.1 14075.0 14076.6 14129.6 21078.4 21351.8 21357.0 21499.4 21589.8 7549.4 7774.8 7797.9 7908.2 8079.3 1054.7 1061.8 1082.4 1112.9 1115.7 35101.5 35292.0 35302.0 35481.6 35555.0 1169.3 1187.4 1191.4 1196.9 1223.1 18179.7 18709.3 18735.4 18866.0 18928.7 608537.0 613917.0 614472.0 614541.0 616197.0 64843.0 65395.0 65528.3 65568.7 65636.0 1323.9 1374.3 1405.3 1445.8 1481.1 31548.4 36403.8 36578.6 36657.3 36790.3 55765.7 56090.0 56309.4 56434.4 56475.0 418153.0 419199.0 419523.0 422344.0 422624.0 31035.4 31798.2 31810.2 31828.2 31830.0 53180.0 54209.2 55211.0 55372.4 55709.0 44109.3 45721.8 46007.3 46257.0 47032.3 45027.5 45747.3 46061.0 46538.8 46967.5 44351.8 44759.6 45102.8 46069.3 46204.0 38575.0 39886.8 40345.8 40718.6 41123.8 30943.8 31389.8 31403.7 31426.5 31499.0 11:22:12 INFO - PROCESS | 14708 | 11:22:12 INFO - PROCESS | 14708 | [#2] jquery.html Cycles:180 Average:29814.49 Median:11997.07 stddev:85776.30 (715.0%) stddev-sans-first:85988.53 11:22:12 INFO - PROCESS | 14708 | Values: 421.6 427.1 435.2 439.8 441.7 19179.3 20452.8 20977.1 21258.5 21525.5 19401.7 19426.5 21249.5 21464.1 21818.2 5305.5 5532.4 5554.9 5559.5 5560.7 5216.2 5315.8 5342.9 5346.0 5510.6 5290.5 5303.2 5340.1 5342.5 5367.0 5303.7 5364.7 5495.9 5511.0 5527.0 5354.1 5364.8 5366.8 5367.8 5369.3 14363.8 14448.6 14467.1 14527.9 14551.1 2490.2 2545.3 2572.9 2644.8 2760.2 10844.0 10904.2 11099.4 11300.8 11441.6 1956.8 2008.2 2050.4 2084.6 2183.7 61141.0 69248.0 70323.6 84625.0 89888.6 11719.6 11866.1 11933.9 11961.8 11962.4 14626.2 14925.9 15414.5 15470.7 16075.1 6706.2 6868.5 7965.9 8240.8 8482.9 7837.8 7860.3 7878.4 8021.4 8031.6 4985.8 5039.3 5043.4 5136.9 5150.4 2071.9 2139.6 2141.8 2175.9 2219.0 984.6 990.6 1000.8 1015.5 1049.8 18445.4 19973.3 20128.4 20901.6 21247.0 1042.3 1054.9 1056.0 1096.8 1115.4 11015.8 11334.7 11670.8 11714.6 11750.5 485720.0 492060.0 534272.0 541517.0 548523.0 87798.0 89872.6 92025.5 103283.5 103833.0 1075.7 1079.1 1097.5 1115.8 1123.1 18354.5 18659.9 18840.7 18853.9 19031.5 41978.8 42552.4 43839.6 43940.8 44245.0 55558.0 56747.4 58421.0 58576.7 64705.4 8831.4 8953.5 9002.6 9100.9 9379.2 9886.1 13254.1 13452.5 13615.4 13721.0 11524.3 11872.9 12001.2 12067.4 12091.5 10771.8 11328.9 11465.7 11796.1 11895.2 11822.6 11859.0 12182.7 12348.0 12549.2 8592.5 8733.6 8741.3 8825.5 9257.4 10897.3 11268.5 11828.5 11916.2 12226.7 11:22:12 INFO - PROCESS | 14708 | 11:22:12 INFO - PROCESS | 14708 | [#3] mootools.html Cycles:180 Average:6339.60 Median:2613.19 stddev:3764.53 (144.1%) stddev-sans-first:3756.94 11:22:12 INFO - PROCESS | 14708 | Values: 1419.9 1555.4 1582.1 1780.3 1898.4 9101.2 9609.1 9612.9 9884.1 9885.4 9817.4 9949.3 9951.5 9952.9 9955.3 2465.2 2491.3 2491.9 2492.8 2495.0 2411.3 2493.7 2498.0 2502.8 2503.1 2434.3 2434.7 2454.7 2461.2 2462.7 2320.0 2350.2 2377.3 2392.2 2401.7 2380.3 2385.2 2387.2 2389.2 2389.3 9217.2 9832.1 9864.9 9932.0 9948.9 5413.4 5623.6 5631.8 5633.9 5635.0 3464.0 3524.5 3546.4 3585.2 3595.2 1185.8 1195.7 1225.6 1227.4 2278.1 13413.7 14030.2 14047.0 14067.6 14100.3 4726.5 4752.3 4825.8 4857.9 4939.3 11073.9 11144.4 11157.7 11171.1 11184.3 5597.3 5608.6 5609.9 5611.3 5613.3 4300.6 4584.9 5175.5 5218.4 5261.4 4172.4 4188.7 4189.2 4205.9 4227.3 1223.5 1253.0 1258.6 1260.5 1279.6 937.6 942.4 1046.2 1054.3 1059.8 6252.6 6280.4 6341.4 6365.6 6609.2 474.1 477.2 478.9 484.6 487.3 4191.3 4372.8 4442.0 4448.6 4452.9 12037.3 12313.3 12430.4 12436.3 12472.2 11733.2 12190.9 12215.6 12355.9 12665.1 8869.0 9049.4 9065.5 9098.8 9102.4 6310.6 6561.1 6580.1 6592.0 6664.5 11361.4 11528.4 11542.5 11630.5 11634.7 9415.9 10388.5 11822.2 11870.4 11948.0 8012.8 8279.3 8597.6 8796.6 8805.3 9369.0 9453.1 9458.2 9478.4 9715.0 8601.7 8675.8 8678.8 8779.0 8856.4 8504.0 8538.4 8588.5 8691.4 8777.5 7510.5 7598.6 7603.6 7604.9 7632.3 5435.7 5447.9 5551.2 5573.6 5622.6 5130.8 5181.8 5203.2 5241.0 5335.8 11:22:12 INFO - PROCESS | 14708 | 11:22:12 INFO - PROCESS | 14708 | [#4] prototype.html Cycles:180 Average:4869.80 Median:5594.72 stddev:1405.34 (25.1%) stddev-sans-first:1367.82 11:22:12 INFO - PROCESS | 14708 | Values: 354.9 359.5 360.6 365.4 369.5 5557.4 5599.5 5618.2 5637.0 5641.4 5279.4 5315.7 5412.0 5501.8 5504.8 4354.4 4369.0 4384.1 4385.1 4406.1 4285.6 4404.5 4412.4 4436.6 4438.4 4347.0 4352.6 4369.5 4369.5 4390.9 4352.6 4352.9 4363.6 4373.8 4392.9 3855.3 3879.6 3895.7 3925.6 3949.0 5201.3 5310.3 5314.7 5324.4 5443.5 4998.9 5000.6 5006.3 5016.3 5033.3 5737.8 5738.3 5748.7 5751.7 5780.7 3971.6 4145.6 4178.7 4186.3 4192.8 5919.2 5944.6 5971.6 6001.5 6020.9 4331.5 4352.2 4364.1 4368.5 4386.2 4155.3 4312.5 4361.8 4378.9 4380.9 4943.6 4974.9 4978.6 4989.9 5006.7 5651.2 5782.6 5799.7 5822.8 5852.7 1440.1 1463.4 1467.2 1469.1 1471.5 981.9 992.2 1009.0 1019.8 1025.4 5626.8 5637.5 5665.1 5676.1 5688.8 5703.8 5893.2 5912.5 5913.2 5974.2 5539.4 5570.3 5589.9 5600.5 5621.5 3081.9 3095.2 3120.0 3132.2 3157.5 5888.4 5985.9 6008.3 6009.3 6012.4 5873.4 5897.8 5923.3 5953.3 5957.2 5720.9 5963.6 6022.9 6025.0 6082.3 5792.4 5832.6 5841.7 5843.3 5858.6 5576.5 5741.0 5798.0 5802.5 5809.5 5987.8 5987.9 6020.5 6049.8 6053.8 4244.9 4252.7 4254.4 4256.1 4264.1 5635.6 5795.5 5893.7 5898.8 5902.4 5716.5 5717.2 5725.7 5743.4 5755.8 5733.6 5740.8 5754.8 5765.9 5792.2 5327.2 5531.6 5706.5 5804.8 5817.3 5846.5 5851.9 5859.0 5859.8 5879.7 5442.7 5685.6 5715.3 5732.9 5742.3 11:22:12 INFO - PROCESS | 14708 | 11:22:12 INFO - PROCESS | 14708 | [#5] yui.html Cycles:180 Average:22098.34 Median:13635.28 stddev:32199.39 (236.1%) stddev-sans-first:32250.02 11:22:12 INFO - PROCESS | 14708 | Values: 806.0 854.6 872.1 926.5 935.8 15967.8 17604.4 17628.2 17648.7 17656.6 14243.5 14267.3 14277.7 14312.4 15993.8 758.8 882.7 887.0 902.7 915.1 906.2 908.5 909.1 909.4 909.6 958.2 959.7 960.4 961.4 962.0 955.6 957.1 957.9 958.7 958.7 698.2 700.7 700.8 700.8 701.0 14861.3 14895.3 14907.7 14939.3 14960.9 15276.5 16131.2 16151.9 16177.3 16624.3 12570.5 12766.8 12958.4 12984.0 13920.1 4138.5 4173.4 4218.0 4237.6 4238.1 79868.0 80958.0 81791.0 83429.0 83966.6 12341.9 12363.8 12410.2 12562.0 12728.7 21419.5 22125.3 22329.3 23134.3 23423.2 9161.0 9183.5 9533.9 9683.0 9797.5 5213.8 5285.2 5377.9 5388.3 5649.7 3847.4 3972.3 3979.2 3982.8 3985.6 683.3 684.2 694.5 696.3 711.8 758.1 801.3 820.5 824.9 858.9 10702.5 10742.7 10796.7 10990.7 11213.2 646.6 725.9 737.4 745.7 749.6 4937.5 5316.4 5538.9 5567.2 5657.3 53932.0 90872.0 133544.0 139996.3 140661.0 111625.0 115373.4 115537.4 117269.4 117582.4 58977.7 94883.5 100654.5 101654.0 103643.5 10422.2 10467.1 10482.8 10677.3 10734.2 55420.0 56769.0 56890.0 57496.8 57847.1 49417.3 49612.0 79599.8 86915.0 89060.0 12903.9 13009.2 13431.1 13668.2 13840.3 15329.2 15389.2 15806.8 16303.5 16374.5 14497.7 14704.8 14933.1 15259.2 15508.6 13953.7 14132.5 14730.0 14853.6 15903.4 12343.3 14478.2 14626.1 14875.3 15166.2 11542.3 12305.9 12828.9 12906.9 13340.4 12181.5 12426.9 12430.6 12484.2 12613.5 11:22:12 INFO - PROCESS | 14708 | -------- Summary: end -------- 11:22:12 INFO - PROCESS | 14708 | 11:22:12 INFO - TEST-INFO | 14708: exit 0 11:22:12 INFO - TEST-OK | dromaeo_css | took 1113659ms 11:22:12 INFO - TEST-START | kraken 11:22:12 INFO - operating with platform_type : linux_ 11:22:12 INFO - Initialising browser for kraken test... 11:22:12 INFO - TEST-INFO | started process 14900 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcG9U6C/profile http://localhost:45127/getInfo.html) 11:22:17 INFO - PROCESS | 14900 | __metrics Screen width/height:1600/1200 11:22:17 INFO - PROCESS | 14900 | colorDepth:24 11:22:17 INFO - PROCESS | 14900 | Browser inner width/height: 1024/697 11:22:17 INFO - PROCESS | 14900 | __metrics 11:22:18 INFO - PROCESS | 14900 | 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" 11:22:18 INFO - PROCESS | 14900 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:22:18 INFO - PROCESS | 14900 | console.error: PushService: 11:22:18 INFO - PROCESS | 14900 | stateChangeProcessEnqueue: Error transitioning state 11:22:18 INFO - PROCESS | 14900 | UnknownError 11:22:19 INFO - TEST-INFO | 14900: exit 0 11:22:19 INFO - Browser initialized. 11:22:19 INFO - Running cycle 1/1 for kraken test... 11:22:19 INFO - TEST-INFO | started process 14991 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcG9U6C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:22:31 INFO - PROCESS | 14991 | RSS: Main: 144015360 11:22:31 INFO - PROCESS | 14991 | 11:22:33 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45127/tests/kraken/driver.html?testName=audio-beat-detection) 11:22:34 INFO - PROCESS | 14991 | RSS: Main: 198750208 11:22:34 INFO - PROCESS | 14991 | 11:22:37 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45127/tests/kraken/driver.html?testName=audio-dft) 11:22:37 INFO - PROCESS | 14991 | RSS: Main: 183820288 11:22:37 INFO - PROCESS | 14991 | 11:22:40 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45127/tests/kraken/driver.html?testName=audio-fft) 11:22:41 INFO - PROCESS | 14991 | RSS: Main: 274640896 11:22:41 INFO - PROCESS | 14991 | 11:22:43 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45127/tests/kraken/driver.html?testName=audio-oscillator) 11:22:43 INFO - PROCESS | 14991 | RSS: Main: 179056640 11:22:43 INFO - PROCESS | 14991 | 11:22:45 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45127/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:22:46 INFO - PROCESS | 14991 | RSS: Main: 178532352 11:22:46 INFO - PROCESS | 14991 | 11:22:49 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45127/tests/kraken/driver.html?testName=imaging-darkroom) 11:22:50 INFO - PROCESS | 14991 | RSS: Main: 215007232 11:22:50 INFO - PROCESS | 14991 | 11:22:53 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45127/tests/kraken/driver.html?testName=imaging-desaturate) 11:22:54 INFO - PROCESS | 14991 | RSS: Main: 214298624 11:22:54 INFO - PROCESS | 14991 | 11:22:56 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45127/tests/kraken/driver.html?testName=json-parse-financial) 11:22:57 INFO - PROCESS | 14991 | RSS: Main: 213176320 11:22:57 INFO - PROCESS | 14991 | 11:22:58 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45127/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:22:59 INFO - PROCESS | 14991 | RSS: Main: 175796224 11:22:59 INFO - PROCESS | 14991 | 11:23:01 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45127/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:23:01 INFO - PROCESS | 14991 | RSS: Main: 182317056 11:23:01 INFO - PROCESS | 14991 | 11:23:03 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45127/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:23:04 INFO - PROCESS | 14991 | RSS: Main: 188706816 11:23:04 INFO - PROCESS | 14991 | 11:23:06 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45127/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:23:07 INFO - PROCESS | 14991 | RSS: Main: 197103616 11:23:07 INFO - PROCESS | 14991 | 11:23:10 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45127/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:23:10 INFO - PROCESS | 14991 | RSS: Main: 194002944 11:23:10 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:45127/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45127/tests/kraken/driver.html?testName=ai-astar) 11:23:12 INFO - PROCESS | 14991 | RSS: Main: 243220480 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | __start_tp_report 11:23:12 INFO - PROCESS | 14991 | _x_x_mozilla_page_load 11:23:12 INFO - PROCESS | 14991 | _x_x_mozilla_page_load_details 11:23:12 INFO - PROCESS | 14991 | |i|pagename|runs| 11:23:12 INFO - PROCESS | 14991 | |0;ai-astar;110;111;120;111;110;109;111;112;109;110 11:23:12 INFO - PROCESS | 14991 | |1;audio-beat-detection;151;114;142;185;116;146;149;120;181;119 11:23:12 INFO - PROCESS | 14991 | |2;audio-dft;161;161;160;159;160;160;158;160;161;159 11:23:12 INFO - PROCESS | 14991 | |3;audio-fft;74;78;74;176;75;75;75;76;170;75 11:23:12 INFO - PROCESS | 14991 | |4;audio-oscillator;86;84;163;83;83;84;145;82;83;138 11:23:12 INFO - PROCESS | 14991 | |5;imaging-gaussian-blur;117;117;116;117;117;116;117;117;117;117 11:23:12 INFO - PROCESS | 14991 | |6;imaging-darkroom;145;145;146;145;146;146;146;146;145;145 11:23:12 INFO - PROCESS | 14991 | |7;imaging-desaturate;94;94;95;94;95;94;94;95;94;94 11:23:12 INFO - PROCESS | 14991 | |8;json-parse-financial;52;51;53;50;51;52;54;51;51;52 11:23:12 INFO - PROCESS | 14991 | |9;json-stringify-tinderbox;62;60;60;61;62;60;60;61;61;60 11:23:12 INFO - PROCESS | 14991 | |10;stanford-crypto-aes;73;74;73;73;78;73;73;73;74;73 11:23:12 INFO - PROCESS | 14991 | |11;stanford-crypto-ccm;120;118;121;127;118;126;119;116;176;122 11:23:12 INFO - PROCESS | 14991 | |12;stanford-crypto-pbkdf2;153;153;150;148;150;152;153;154;150;148 11:23:12 INFO - PROCESS | 14991 | |13;stanford-crypto-sha256-iterative;61;62;62;60;62;60;68;61;60;61 11:23:12 INFO - PROCESS | 14991 | __end_tp_report 11:23:12 INFO - PROCESS | 14991 | __start_cc_report 11:23:12 INFO - PROCESS | 14991 | _x_x_mozilla_cycle_collect,1672 11:23:12 INFO - PROCESS | 14991 | __end_cc_report 11:23:12 INFO - PROCESS | 14991 | __startTimestamp1476987792589__endTimestamp 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | ------- Summary: start ------- 11:23:12 INFO - PROCESS | 14991 | Number of tests: 14 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:3.20 (2.9%) stddev-sans-first:3.36 11:23:12 INFO - PROCESS | 14991 | Values: 110.0 111.0 120.0 111.0 110.0 109.0 111.0 112.0 109.0 110.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:147.50 stddev:25.79 (17.5%) stddev-sans-first:27.17 11:23:12 INFO - PROCESS | 14991 | Values: 151.0 114.0 142.0 185.0 116.0 146.0 149.0 120.0 181.0 119.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.99 (0.6%) stddev-sans-first:0.97 11:23:12 INFO - PROCESS | 14991 | Values: 161.0 161.0 160.0 159.0 160.0 160.0 158.0 160.0 161.0 159.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#3] audio-fft Cycles:10 Average:94.80 Median:75.00 stddev:41.25 (55.0%) stddev-sans-first:43.07 11:23:12 INFO - PROCESS | 14991 | Values: 74.0 78.0 74.0 176.0 75.0 75.0 75.0 76.0 170.0 75.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#4] audio-oscillator Cycles:10 Average:103.10 Median:83.00 stddev:32.04 (38.6%) stddev-sans-first:33.38 11:23:12 INFO - PROCESS | 14991 | Values: 86.0 84.0 163.0 83.0 83.0 84.0 145.0 82.0 83.0 138.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 11:23:12 INFO - PROCESS | 14991 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 11:23:12 INFO - PROCESS | 14991 | Values: 145.0 145.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 11:23:12 INFO - PROCESS | 14991 | Values: 94.0 94.0 95.0 94.0 95.0 94.0 94.0 95.0 94.0 94.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:1.16 (2.2%) stddev-sans-first:1.22 11:23:12 INFO - PROCESS | 14991 | Values: 52.0 51.0 53.0 50.0 51.0 52.0 54.0 51.0 51.0 52.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:61.00 stddev:0.82 (1.3%) stddev-sans-first:0.73 11:23:12 INFO - PROCESS | 14991 | Values: 62.0 60.0 60.0 61.0 62.0 60.0 60.0 61.0 61.0 60.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:73.00 stddev:1.57 (2.1%) stddev-sans-first:1.64 11:23:12 INFO - PROCESS | 14991 | Values: 73.0 74.0 73.0 73.0 78.0 73.0 73.0 73.0 74.0 73.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:121.50 stddev:17.81 (14.7%) stddev-sans-first:18.74 11:23:12 INFO - PROCESS | 14991 | Values: 120.0 118.0 121.0 127.0 118.0 126.0 119.0 116.0 176.0 122.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.10 Median:152.50 stddev:2.18 (1.4%) stddev-sans-first:2.20 11:23:12 INFO - PROCESS | 14991 | Values: 153.0 153.0 150.0 148.0 150.0 152.0 153.0 154.0 150.0 148.0 11:23:12 INFO - PROCESS | 14991 | 11:23:12 INFO - PROCESS | 14991 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:61.50 stddev:2.36 (3.8%) stddev-sans-first:2.49 11:23:12 INFO - PROCESS | 14991 | Values: 61.0 62.0 62.0 60.0 62.0 60.0 68.0 61.0 60.0 61.0 11:23:12 INFO - PROCESS | 14991 | -------- Summary: end -------- 11:23:12 INFO - PROCESS | 14991 | 11:23:13 INFO - TEST-INFO | 14991: exit 0 11:23:13 INFO - TEST-OK | kraken | took 60873ms 11:23:13 INFO - SUITE-END | took 1174s 11:23:13 INFO - Completed test suite (00:19:34) 11:23:13 INFO - javascript benchmark 11:23:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3160.952585711214, 3168.8257145856983, 3169.429502689515, 3190.2183964928595, 3230.951535726963, 5478.150886612571, 5571.9721401393035, 5593.216949627045, 5599.1041433370665, 5618.269624948756, 5617.101263797793, 5681.801136960207, 5693.629914055584, 5698.6865722385255, 5718.914216286748, 5109.4635063318465, 5142.614303927202, 5179.093658609729, 5204.557612602934, 5212.061828870802, 4997.100521906055, 5076.898462030757, 5211.791528338862, 5217.7130257836, 5225.163973764198, 4989.550940415362, 5028.7988480460735, 5044.621653375993, 5115.0, 5139.357580302462, 5127.948720512784, 5135.486451354872, 5138.3577052868395, 5138.845834624967, 5172.043172013189, 4906.165951788204, 5035.320231768701, 5047.848564543033, 5093.083245015896, 5105.336306280197, 5510.834674959758, 5531.806386776484, 5566.693831839248, 5624.91562626561, 5662.405447428002, 6022.3074346450285, 6050.939490605082, 6071.1803906472505, 6071.423214794588, 6078.209832721762, 5858.912116318259, 6095.6952152392205, 6096.024636058254, 6097.298810636789, 6100.328963813977, 5603.691796951166, 5681.460261275172, 5686.033374326333, 5753.510951569122, 5765.452282033284, 6272.247330320391, 6276.372362763778, 6282.340354262737, 6283.59156654816, 6288.717007734572, 5727.5990680652785, 5970.9701451492465, 6010.789622363283, 6024.4879185268865, 6030.819075427744, 5890.440408161217, 5894.056950887837, 5935.0503919372695, 5992.0712289595185, 6044.879102418015, 5925.496332811759, 5983.371745966698, 5988.490978266809, 5990.221271234712, 5992.760289588367, 5898.557608179403, 5972.731227094791, 5980.611260268069, 5985.910211346833, 5995.0, 4531.071130418256, 4536.160810250049, 4544.522825103317, 4581.839635612738, 4612.86161415158, 4082.9795851020554, 4083.3262511685834, 4086.9795651021554, 4087.2234275487563, 4114.979425102855, 5704.030314846486, 5763.394843541451, 5767.163761254678, 5767.250257466587, 5808.419158084243, 5892.145638882338, 5919.437653422918, 5930.584859059911, 5942.3166335871065, 6038.63768173911, 5697.116946873241, 5697.743601537941, 5702.315722113373, 5715.857103572361, 5720.713964301768, 5130.076586214443, 5145.3568303962, 5161.380634323905, 5175.482451754794, 5194.818181363634, 6082.057281121433, 6089.208402907594, 6096.786612468542, 6110.114033465123, 6189.96905015481, 5988.850278742892, 5990.64056156632, 5992.191054207626, 5992.67040312787, 6025.578209525291, 6145.262568491631, 6177.413145751147, 6185.134081228722, 6185.783497577563, 6188.9690551546955, 5797.043487824461, 5862.794802181903, 5880.8235752927485, 5883.0, 6003.489703375088, 6022.0, 6042.5770196087615, 6046.879062418639, 6070.575059745775, 6072.574919755752, 5967.7016149191495, 5997.10043493462, 5999.0, 6001.5798894077, 6005.3694362091355, 5785.045467497984, 5823.592348535732, 5844.035734103825, 5873.853153671193, 5971.193888824953, 5932.466078052996, 5940.762369505172, 5941.881162376815, 5947.6431414115295, 5950.940490595039, 5979.312379076462, 5981.760729570942, 5984.341722410444, 5985.341612422543, 5990.9700451497465, 5611.607187496836, 5707.400722924199, 5714.342850572238, 5721.628094173865, 5853.0], "value": 5544.6086139346235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.9734511946962, 653.8659574787168, 654.6857508395968, 674.5514233035032, 675.3111825937547, 34525.65474345256, 46429.30356044662, 46906.53093469064, 47017.52982470174, 47120.29319560209, 47496.287555686606, 48050.0, 48128.0, 48166.75916620421, 48172.0, 8407.201315874981, 9295.256379489625, 9442.150206481416, 9445.29160312976, 9457.716268511922, 10889.455527223647, 11255.4372281386, 11295.435228238597, 11671.41642917855, 11680.065594752441, 11395.943020284887, 11517.65447036586, 11518.251313664627, 11524.19330646855, 11526.596569120082, 11271.323720576791, 11300.434978251136, 11335.489902954387, 11349.886501135048, 11356.48895799691, 11149.498272577754, 11239.157063220291, 11247.55009799607, 11247.943760281147, 11248.043916267126, 45807.54192458066, 45866.31200531961, 45884.311735323994, 46319.768401158115, 46342.0, 3771.9622803771886, 3820.1595648957186, 3864.3044252034624, 3998.980005099956, 4446.777661116931, 34777.304453911034, 35130.82434587836, 35137.64862351369, 35208.64791352079, 35572.0, 4757.096151731194, 4793.0, 4794.832180873687, 4834.105690447279, 4856.465788763233, 396707.0, 402018.0, 402233.9888300569, 403422.0, 403715.0, 64994.0, 65562.0, 65579.67210163918, 65721.0, 65868.34131658718, 38787.612123878964, 40666.39000414996, 40742.185156296706, 43094.0, 67067.66466167735, 17508.912455437643, 17573.03348315855, 17577.033263170393, 17584.12079396025, 17614.91192544055, 11941.64175074749, 13414.061015729001, 14075.014748967475, 14076.648083797782, 14129.646758831112, 21078.367648970554, 21351.786482135292, 21357.0, 21499.355019349445, 21589.78410215909, 7549.3583045440655, 7774.766756997299, 7797.883031754528, 7908.248716371935, 8079.272865442021, 1054.7415883108688, 1061.7717190804015, 1082.4154956323498, 1112.9499172537255, 1115.6697258682825, 35101.47347789785, 35292.0, 35302.0, 35481.64518354835, 35555.0, 1169.3393232823428, 1187.3825610682397, 1191.4221602522769, 1196.9222000569935, 1223.0887988448662, 18179.727304090447, 18709.25162993465, 18735.437936861916, 18866.0, 18928.71606925897, 608537.0, 613917.0, 614472.0, 614541.0, 616197.0, 64843.0, 65395.0, 65528.344716553176, 65568.67215663892, 65636.0, 1323.860994595573, 1374.29223949666, 1405.3184205660248, 1445.7542217822995, 1481.0669278354567, 31548.369032619677, 36403.817980909924, 36578.634213657526, 36657.26685466329, 36790.26419471649, 55765.72117139388, 56090.0, 56309.43690563206, 56434.43565564292, 56475.0, 418153.0, 419199.0, 419523.0, 422344.0, 422624.0, 31035.379292414476, 31798.205044874063, 31810.204744881565, 31828.204294891886, 31830.0, 53180.0, 54209.18686219631, 55211.0, 55372.44627553673, 55709.0, 44109.33835992398, 45721.77139114283, 46007.30989035234, 46257.0, 47032.29451558298, 45027.549724503646, 45747.31379029251, 46061.0, 46538.76730616325, 46967.530324696316, 44351.77824110859, 44759.55240447554, 45102.77448612736, 46069.30896036495, 46204.0, 38575.0, 39886.800565996986, 40345.798271008454, 40718.59281407267, 41123.7943810279, 30943.845280773454, 31389.802713809815, 31403.68596314099, 31426.52860207053, 31499.0], "value": 22143.65421604003, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.55104813373777, 427.0809020345935, 435.16231254834446, 439.8416570034787, 441.7394232626318, 19179.32872349468, 20452.795472045276, 20977.056032478537, 21258.46853828655, 21525.461863453384, 19401.708974365396, 19426.51433714158, 21249.468763280925, 21464.141434342648, 21818.236361727344, 5305.495977882105, 5532.446755324476, 5554.91667624986, 5559.499645031946, 5560.666360018392, 5216.165413533835, 5315.7873685052555, 5342.893142137155, 5346.03771321162, 5510.559155267569, 5290.497402746733, 5303.204519322094, 5340.145576707728, 5342.545883599899, 5366.973165134188, 5303.681779093247, 5364.6512976656395, 5495.890082198353, 5511.035568775448, 5527.0, 5354.089804733205, 5364.758585863646, 5366.812161574326, 5367.838964831022, 5369.301990741179, 14363.784543231859, 14448.566543003726, 14467.059641123293, 14527.85472145286, 14551.126932384092, 2490.203134996802, 2545.2745967399387, 2572.9099481518056, 2644.8148629595935, 2760.1719484154673, 10844.0, 10904.236703430763, 11099.44502774858, 11300.773984520345, 11441.599544015919, 1956.8238858502802, 2008.1866843928233, 2050.4361300642295, 2084.6247675418417, 2183.705199798023, 61141.0, 69248.0, 70323.64838175777, 84625.0, 89888.5505572468, 11719.648410547697, 11866.110041746904, 11933.940330298294, 11961.820572691417, 11962.40187990597, 14626.195559244132, 14925.850741492663, 15414.537563873102, 15470.690586188439, 16075.115868627337, 6706.195256569145, 6868.484863635247, 7965.880511792212, 8240.752777416687, 8482.872756908651, 7837.7648670539975, 7860.292573668397, 7878.409119316074, 8021.358291336679, 8031.638576264082, 4985.750712464363, 5039.26930595069, 5043.394792624835, 5136.897262054738, 5150.381954165524, 2071.906764195615, 2139.6041732279577, 2141.764405915348, 2175.9020844062056, 2218.966715499269, 984.6159997600942, 990.5988074829561, 1000.8298589239847, 1015.5379302417382, 1049.7795462952713, 18445.354412595494, 19973.300934467516, 20128.39614811558, 20901.581968360548, 21247.0, 1042.3120740311358, 1054.8681414823147, 1056.0442799266675, 1096.8245080787035, 1115.4255558387438, 11015.83476247857, 11334.69281672959, 11670.76658466826, 11714.648560543197, 11750.529978800752, 485720.0, 492060.0, 534272.0, 541517.0, 548523.0, 87798.0, 89872.5506372464, 92025.53987230021, 103283.48358258161, 103833.0, 1075.736444571085, 1079.1312993040679, 1097.5170924793065, 1115.7991561518843, 1123.1412013118302, 18354.541136471697, 18659.90670046641, 18840.717389239446, 18853.90573047126, 19031.52421189481, 41978.79010604927, 42552.361714573686, 43839.56160438355, 43940.78029609959, 44245.0, 55558.0, 56747.432525674216, 58421.0, 58576.707116464146, 64705.35294646993, 8831.425957312755, 8953.462792232227, 9002.594883230402, 9100.863487047567, 9379.155875971195, 9886.060824221688, 13254.138480998705, 13452.529161479693, 13615.387307571382, 13721.0, 11524.250923689933, 11872.940635296769, 12001.219920705125, 12067.396630168632, 12091.516339346328, 10771.784564308513, 11328.886711132784, 11465.713357165803, 11796.115291353011, 11895.167338286583, 11822.645320640395, 11859.0, 12182.6954326139, 12348.0, 12549.247045177319, 8592.484450932963, 8733.56332183401, 8741.34439916996, 8825.514596697243, 9257.444553326823, 10897.291676041032, 11268.492917818881, 11828.46771895284, 11916.225445346025, 12226.694332641755], "value": 9338.281030719545, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.8722115009648, 1555.3778488604557, 1582.050769538277, 1780.2522940365045, 1898.3735367328784, 9101.226395756361, 9609.135177833994, 9612.855807162898, 9884.110430061293, 9885.35745176563, 9817.410955342666, 9949.253805964563, 9951.452670103141, 9952.900470995288, 9955.253355998293, 2465.1618449727043, 2491.3397949543387, 2491.8878650460683, 2492.8130390220717, 2494.9875250623722, 2411.2766170148884, 2493.738157493455, 2497.987510062447, 2502.762237587426, 2503.086373473678, 2434.3183908505557, 2434.732179460258, 2454.6563481112657, 2461.212412028152, 2462.704475462947, 2319.9652005219937, 2350.247920665388, 2377.2511658827416, 2392.2225276785116, 2401.7238017628024, 2380.27401642499, 2385.1890357278608, 2387.224152150558, 2389.175734371639, 2389.2951579284154, 9217.216536594398, 9832.115109640166, 9864.852027219598, 9931.950340248324, 9948.90051099487, 5413.350397952231, 5623.634463759883, 5631.8028868989795, 5633.915491267634, 5634.971825140848, 3463.9826800866085, 3524.488949102366, 3546.414841551141, 3585.2470981094, 3595.2449985503076, 1185.82805493203, 1195.7433918790764, 1225.5526732742485, 1227.4476485581524, 2278.065992942902, 13413.66465838352, 14030.228337441436, 14047.0, 14067.648308792259, 14100.294985250799, 4726.527347265314, 4752.334673145762, 4825.806967721322, 4857.927131093036, 4939.2838038484215, 11073.889261107446, 11144.387058711849, 11157.72105697364, 11171.106311495061, 11184.328940263611, 5597.328320601554, 5608.6073974821375, 5609.859753506195, 5611.270534830446, 5613.298337707786, 4300.634446072119, 4584.908301833944, 5175.534201921845, 5218.399884013387, 5261.394939581921, 4172.395002724588, 4188.694389805214, 4189.172308691621, 4205.852795152155, 4227.281362168439, 1223.5044806853239, 1252.9976019184799, 1258.5783762439685, 1260.5273022616518, 1279.5905310300614, 937.6062053937362, 942.3639043645512, 1046.199657262195, 1054.2936232724094, 1059.7827445373835, 6252.562320637603, 6280.434760871543, 6341.397567231105, 6365.58623689468, 6609.206895172514, 474.0542617478153, 477.1721063954037, 478.89943111946883, 484.6074679509608, 487.349388566268, 4191.287481128215, 4372.825086996484, 4442.022754993896, 4448.621867141329, 4452.866414007585, 12037.277763334228, 12313.322767247888, 12430.37848107591, 12436.316002619942, 12472.189307694971, 11733.237339572901, 12190.878091218974, 12215.633530994084, 12355.93822030884, 12665.050121240944, 8868.955655221682, 9049.411788233743, 9065.501397423073, 9098.772530686654, 9102.362834601514, 6310.558260921692, 6561.1470508833545, 6580.144581204413, 6592.044153597797, 6664.466842652673, 11361.431928403712, 11528.365939873387, 11542.538298467967, 11630.476628551905, 11634.6509604712, 9415.858762118436, 10388.48057597102, 11822.231554948901, 11870.406479675808, 11948.0, 8012.83974320522, 8279.254867061993, 8597.570121493774, 8796.60415281301, 8805.339599530189, 9369.0, 9453.101955314234, 9458.243340532603, 9478.431294122374, 9715.0, 8601.655933762579, 8675.783105422415, 8678.78303042429, 8778.956105219433, 8856.380053396459, 8504.0, 8538.402311838112, 8588.527630980107, 8691.435056721293, 8777.51723655205, 7510.511816731895, 7598.582077985543, 7603.581803000667, 7604.885926711215, 7632.313091821763, 5435.728213589226, 5447.945520544902, 5551.2228288040515, 5573.581981351496, 5622.6064175508955, 5130.820421285237, 5181.766820493162, 5203.16749320107, 5241.0, 5335.839924802262], "value": 4921.595744556253, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.886436340371, 359.4805506043767, 360.5817251987694, 365.36244253777164, 369.51962448816533, 5557.360903496099, 5599.524040456566, 5618.185363122345, 5636.985342638323, 5641.379448260688, 5279.392869819972, 5315.681059136455, 5412.0, 5501.807436739715, 5504.752286147123, 4354.368616550598, 4368.978155109236, 4384.123175364932, 4385.144896745136, 4406.140802543506, 4285.57144285572, 4404.515503294635, 4412.40432541606, 4436.622887054604, 4438.378626992224, 4346.9565304347025, 4352.608265256126, 4369.497507786597, 4369.541198174194, 4390.890227744316, 4352.564743525654, 4352.912941741163, 4363.629091527224, 4373.7813109344725, 4392.868213953587, 3855.306044911915, 3879.5538513071074, 3895.668868146183, 3925.568187499373, 3948.960510394888, 5201.323827902386, 5310.2565640809935, 5314.707691076989, 5324.444266751942, 5443.455654434564, 4998.925016124761, 5000.599952003831, 5006.299118123466, 5016.272640467112, 5033.3205017322925, 5737.799177028784, 5738.282714660668, 5748.7125643717645, 5751.654900705971, 5780.710964451802, 3971.6425521702895, 4145.585441455861, 4178.749275043477, 4186.267403204458, 4192.790360481994, 5919.230500035054, 5944.554158438091, 5971.552133589955, 6001.5198784097165, 6020.909686354708, 4331.45856767904, 4352.194843953878, 4364.127174565099, 4368.5194628590825, 4386.210482113186, 4155.3143731284, 4312.525622181557, 4361.80371883266, 4378.868633940987, 4380.8685739427865, 4943.555080042814, 4974.905520785422, 4978.626603004789, 4989.925151122736, 5006.674566153261, 5651.208830763697, 5782.624129431658, 5799.652020878761, 5822.82531524055, 5852.707364631837, 1440.1431148466645, 1463.3853781411833, 1467.23703674091, 1469.1478942213491, 1471.529110684592, 981.9165370943407, 992.2012779712256, 1008.9899101008896, 1019.8266294729914, 1025.415513157493, 5626.774929002794, 5637.49262566371, 5665.093585026376, 5676.083502383342, 5688.829335119953, 5703.771849126071, 5893.233879595625, 5912.526997840162, 5913.201717768132, 5974.163617093611, 5539.41836107211, 5570.303712035996, 5589.916151257735, 5600.4959553640365, 5621.494065534122, 3081.9083703076017, 3095.2416657918952, 3120.018788069592, 3132.201288671374, 3157.4790159623863, 5888.440598143169, 5985.850353741104, 6008.309044459856, 6009.338972712998, 6012.368701286389, 5873.383294754075, 5897.761470091329, 5923.318818335946, 5953.3451320354725, 5957.195778569924, 5720.85582883425, 5963.5527335450015, 6022.879542409215, 6024.969875150597, 6082.330943596201, 5792.4497172768515, 5832.591718579659, 5841.678707671119, 5843.328017278068, 5858.5898987070495, 5576.466471720147, 5741.0, 5798.043322851851, 5802.5357971361345, 5809.535237180931, 5987.790427335022, 5987.910181347371, 6020.54845886569, 6049.848753781191, 6053.75784968614, 4244.893877653083, 4252.681048921281, 4254.383114448449, 4256.084941737541, 4264.147170565837, 5635.577331700057, 5795.507381872588, 5893.705314734332, 5898.8230353089475, 5902.380250073852, 5716.514096301861, 5717.199592057199, 5725.6850873202375, 5743.4256574342235, 5755.798547051, 5733.627314224562, 5740.7944331690815, 5754.827355179351, 5765.91351129725, 5792.160136780228, 5327.200919861897, 5531.612787104864, 5706.457886500776, 5804.82585522435, 5817.272840894888, 5846.503047240862, 5851.912221316768, 5858.970705146447, 5859.794907178229, 5879.676617786063, 5442.673439593637, 5685.573581981285, 5715.31416230055, 5732.914006289992, 5742.339630942495], "value": 4425.315978380283, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.9717909873156, 854.6111519258734, 872.1409411729447, 926.5089502563644, 935.7941252924356, 15967.840321596781, 17604.38384656537, 17628.206730697133, 17648.735268970977, 17656.646867062653, 14243.501477448292, 14267.286635668226, 14277.714445711079, 14312.35594398252, 15993.760093598576, 758.8102974256436, 882.6954700628289, 887.0464250930244, 902.6930843513204, 915.0849150849151, 906.188960880012, 908.5457271364318, 909.1045320359474, 909.4452384045727, 909.6225066597354, 958.1807554540871, 959.6928982725531, 960.4189465373453, 961.4183419031456, 961.9855702164473, 955.574769227694, 957.1385752822446, 957.8658987741757, 958.7315551645551, 958.7459323279337, 698.2075344483997, 700.726716580534, 700.7827573452195, 700.7967689370076, 700.9719611215596, 14861.256937153099, 14895.255237238094, 14907.70184596302, 14939.327730252056, 14960.925195374062, 15276.520839756166, 16131.193440328054, 16151.91924040384, 16177.272022759003, 16624.33502659892, 12570.497180112785, 12766.80849787254, 12958.352082395842, 12984.0, 13920.095193812369, 4138.544760076389, 4173.373993900909, 4218.029853133797, 4237.639800616951, 4238.109996900624, 79868.0, 80958.0, 81791.0, 83429.0, 83966.58016709877, 12341.876581234073, 12363.814542781865, 12410.197217855131, 12562.0, 12728.6817829555, 21419.464513386978, 22125.336239912827, 22329.330120096423, 23134.305970820904, 23423.180188693314, 9161.0, 9183.494907780003, 9533.904660953302, 9682.983286754788, 9797.510124493747, 5213.843584692389, 5285.233641122016, 5377.865553361197, 5388.326459192601, 5649.717514124277, 3847.3844184930276, 3972.2651309507833, 3979.224051309967, 3982.761034337949, 3985.6412922837085, 683.255251775567, 684.1755684400285, 694.4722011271369, 696.2793508718452, 711.8220444888777, 758.0978635423828, 801.2828518476048, 820.4789958376474, 824.8927639406838, 858.8969323681198, 10702.518386672618, 10742.73143171427, 10796.730081748019, 10990.725231869268, 11213.21507494484, 646.615263917968, 725.8983742276085, 737.4284929179821, 745.6905384265577, 749.6102026945957, 4937.481564435682, 5316.388615309212, 5538.944610553924, 5567.164925261162, 5657.349404818498, 53932.0, 90872.0, 133544.0, 139996.30001849926, 140661.0, 111625.0, 115373.42313288548, 115537.4223128879, 117269.4136529312, 117582.41208793901, 58977.70511147417, 94883.52558237164, 100654.4967275159, 101654.0, 103643.4817825921, 10422.166226701996, 10467.110295624652, 10482.790344193227, 10677.252592318464, 10734.248602597743, 55420.0, 56769.0, 56890.0, 57496.837673928334, 57847.13229301648, 49417.258741118196, 49612.0, 79599.81085587761, 86915.0, 89060.0, 12903.870961290642, 13009.21944683322, 13431.12697674648, 13668.179909205479, 13840.307984600931, 15329.233538323262, 15389.230538473255, 15806.76289855676, 16303.510894673178, 16374.508764737076, 14497.710045799236, 14704.77942830837, 14933.10401375921, 15259.23703814827, 15508.612284692974, 13953.650531181196, 14132.50536231227, 14730.0, 14853.554393368217, 15903.443379481663, 12343.318144468585, 14478.203698796668, 14626.122432653649, 14875.256237188314, 15166.165860877758, 11542.307461552333, 12305.93847030759, 12828.935855320664, 12906.870931290567, 13340.399682014138, 12181.512739490321, 12426.937865310616, 12430.627081187578, 12484.188527745668, 12613.49546018149], "value": 8224.220978978508, "unit": "score"}], "name": "dromaeo_css", "value": 7681.206090468685, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 120.0, 111.0, 110.0, 109.0, 111.0, 112.0, 109.0, 110.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 142.0, 185.0, 116.0, 146.0, 149.0, 120.0, 181.0, 119.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 159.0, 160.0, 160.0, 158.0, 160.0, 161.0, 159.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 74.0, 176.0, 75.0, 75.0, 75.0, 76.0, 170.0, 75.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 163.0, 83.0, 83.0, 84.0, 145.0, 82.0, 83.0, 138.0], "value": 103.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 50.0, 51.0, 52.0, 54.0, 51.0, 51.0, 52.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 61.0, 62.0, 60.0, 60.0, 61.0, 61.0, 60.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 73.0, 78.0, 73.0, 73.0, 73.0, 74.0, 73.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 118.0, 121.0, 127.0, 118.0, 126.0, 119.0, 116.0, 176.0, 122.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 150.0, 148.0, 150.0, 152.0, 153.0, 154.0, 150.0, 148.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 60.0, 62.0, 60.0, 68.0, 61.0, 60.0, 61.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1493.1999999999998, "alertThreshold": 2.0}]} 11:23:13 INFO - Return code: 0 11:23:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:23:13 INFO - # TBPL SUCCESS # 11:23:13 INFO - Running post-action listener: _resource_record_post_action 11:23:13 INFO - [mozharness: 2016-10-20 18:23:13.888638Z] Finished run-tests step (success) 11:23:13 INFO - Running post-run listener: _resource_record_post_run 11:23:13 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 286474240; Read time: 180; Write time: 452804 11:23:13 INFO - TinderboxPrint: CPU usage
12.9% 11:23:13 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 180 11:23:13 INFO - TinderboxPrint: I/O write bytes / time
286,474,240 / 452,804 11:23:13 INFO - TinderboxPrint: CPU idle
8,270.8 (87.1%) 11:23:13 INFO - TinderboxPrint: CPU user
1,179.6 (12.4%) 11:23:13 INFO - TinderboxPrint: Swap in / out
0 / 0 11:23:13 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 71319552; Read time: 0; Write time: 113792 11:23:13 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 215154688; Read time: 0; Write time: 339012 11:23:14 INFO - Running post-run listener: _upload_blobber_files 11:23:14 INFO - Blob upload gear active. 11:23:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:23:14 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 11:23:14 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'] 11:23:14 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 11:23:14 INFO - (blobuploader) - INFO - Open directory for files ... 11:23:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:23:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:23:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:21 INFO - (blobuploader) - INFO - Done attempting. 11:23:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:23:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:23:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:22 INFO - (blobuploader) - INFO - Done attempting. 11:23:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:23:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:23:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:23 INFO - (blobuploader) - INFO - Done attempting. 11:23:23 INFO - (blobuploader) - INFO - Iteration through files over. 11:23:23 INFO - Return code: 0 11:23:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:23:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:23:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d60716d7f16f92c192dcc62ff30e45ffdb33f86137c622dbc01597557b6f6040a55bd47edcbcfa762c1dbdd9a84a8ca1163e23debc0494b784db2aa5828a9a4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73f9e6beeb9d745fc7c37162c285630e61e8ca2f7549937cc83d9ac20e99ec086be542342dbe396b7bae157c913d85c8d570ad4a02900c3a201d8e8241bf1b61", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2792e52c500b43d972acddb4634898c4758ec016c24a603352660f9d3e885fdcd27e238343050bf5cef3097b7170ba06bb484b5c4db719d6067408e10a7df06a"} 11:23:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:23:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:23:23 INFO - Contents: 11:23:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d60716d7f16f92c192dcc62ff30e45ffdb33f86137c622dbc01597557b6f6040a55bd47edcbcfa762c1dbdd9a84a8ca1163e23debc0494b784db2aa5828a9a4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73f9e6beeb9d745fc7c37162c285630e61e8ca2f7549937cc83d9ac20e99ec086be542342dbe396b7bae157c913d85c8d570ad4a02900c3a201d8e8241bf1b61", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2792e52c500b43d972acddb4634898c4758ec016c24a603352660f9d3e885fdcd27e238343050bf5cef3097b7170ba06bb484b5c4db719d6067408e10a7df06a"} 11:23:23 INFO - Running post-run listener: copy_logs_to_upload_dir 11:23:23 INFO - Copying logs to upload dir... 11:23:23 INFO - mkdir: /builds/slave/test/build/upload/logs 11:23:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.063398 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-10-20 11:23:22.988991) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 11:23:22.992104) ========= 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=745a4373a2a16df560f216e000000264-1476986118.141679-1378209174 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/d60716d7f16f92c192dcc62ff30e45ffdb33f86137c622dbc01597557b6f6040a55bd47edcbcfa762c1dbdd9a84a8ca1163e23debc0494b784db2aa5828a9a4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73f9e6beeb9d745fc7c37162c285630e61e8ca2f7549937cc83d9ac20e99ec086be542342dbe396b7bae157c913d85c8d570ad4a02900c3a201d8e8241bf1b61", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2792e52c500b43d972acddb4634898c4758ec016c24a603352660f9d3e885fdcd27e238343050bf5cef3097b7170ba06bb484b5c4db719d6067408e10a7df06a"} build_url:https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/Okhcfv1SQvSpiw0xVP2Qog/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/d60716d7f16f92c192dcc62ff30e45ffdb33f86137c622dbc01597557b6f6040a55bd47edcbcfa762c1dbdd9a84a8ca1163e23debc0494b784db2aa5828a9a4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73f9e6beeb9d745fc7c37162c285630e61e8ca2f7549937cc83d9ac20e99ec086be542342dbe396b7bae157c913d85c8d570ad4a02900c3a201d8e8241bf1b61", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2792e52c500b43d972acddb4634898c4758ec016c24a603352660f9d3e885fdcd27e238343050bf5cef3097b7170ba06bb484b5c4db719d6067408e10a7df06a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 11:23:23.017757) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 11:23:23.018110) ========= 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=745a4373a2a16df560f216e000000264-1476986118.141679-1378209174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 11:23:23.102803) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 11:23:23.103152) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 11:23:23.103478) ========= ========= Total master_lag: 0.17 =========