builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1471905679.22 results: success (0) buildid: 20160822124915 builduid: 63bc2500c2334edda63f0710f73a35f8 revision: 07189ee916253fe9692528551ad1b96bc6c1bccc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:19.217450) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:19.217917) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:19.218209) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:19.233518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:19.233836) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-22 15:41:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-08-22 15:41:19 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106682 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:19.354564) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:19.354948) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020381 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:19.405265) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 15:41:19.405647) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 07189ee916253fe9692528551ad1b96bc6c1bccc --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-aurora --rev 07189ee916253fe9692528551ad1b96bc6c1bccc --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=e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-22 15:41:19,460 truncating revision to first 12 chars 2016-08-22 15:41:19,460 Setting DEBUG logging. 2016-08-22 15:41:19,461 attempt 1/10 2016-08-22 15:41:19,461 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/07189ee91625?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 15:41:20,226 unpacking tar archive at: mozilla-aurora-07189ee91625/testing/mozharness/ program finished with exit code 0 elapsedTime=1.049776 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 15:41:20.469996) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:20.470404) ========= script_repo_revision: 07189ee916253fe9692528551ad1b96bc6c1bccc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:20.470835) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:20.471273) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 15:41:20.480665) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-22 15:41:20.480947) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:41:20 INFO - MultiFileLogger online at 20160822 15:41:20 in /builds/slave/test 15:41:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:41:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:41:20 INFO - {'append_to_log': False, 15:41:20 INFO - 'base_work_dir': '/builds/slave/test', 15:41:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:41:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:41:20 INFO - 'branch': 'Mozilla-Aurora', 15:41:20 INFO - 'buildbot_json_path': 'buildprops.json', 15:41:20 INFO - 'config_files': ('talos/linux_config.py',), 15:41:20 INFO - 'default_actions': ('clobber', 15:41:20 INFO - 'read-buildbot-config', 15:41:20 INFO - 'download-and-extract', 15:41:20 INFO - 'populate-webroot', 15:41:20 INFO - 'create-virtualenv', 15:41:20 INFO - 'install', 15:41:20 INFO - 'run-tests'), 15:41:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:41:20 INFO - 'download_minidump_stackwalk': True, 15:41:20 INFO - 'download_symbols': 'ondemand', 15:41:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:41:20 INFO - 'tooltool.py': '/tools/tooltool.py', 15:41:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:41:20 INFO - '/tools/misc-python/virtualenv.py')}, 15:41:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:41:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:41:20 INFO - 'installer_path': 'installer.exe', 15:41:20 INFO - 'log_level': 'info', 15:41:20 INFO - 'log_name': 'talos', 15:41:20 INFO - 'log_to_console': True, 15:41:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:41:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:41:20 INFO - 'opt_config_files': (), 15:41:20 INFO - 'pip_index': False, 15:41:20 INFO - 'sps_profile': False, 15:41:20 INFO - 'sps_profile_interval': 0, 15:41:20 INFO - 'suite': 'dromaeojs', 15:41:20 INFO - 'system_bits': '32', 15:41:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:41:20 INFO - 'title': 'talos-linux64-ix-006', 15:41:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:41:20 INFO - 'use_talos_json': True, 15:41:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:41:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:41:20 INFO - 'work_dir': 'build'} 15:41:20 INFO - [mozharness: 2016-08-22 22:41:20.626744Z] Running clobber step. 15:41:20 INFO - Running pre-action listener: _resource_record_pre_action 15:41:20 INFO - Running main action method: clobber 15:41:20 INFO - rmtree: /builds/slave/test/build 15:41:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:41:21 INFO - Running post-action listener: _resource_record_post_action 15:41:21 INFO - [mozharness: 2016-08-22 22:41:21.597907Z] Finished clobber step (success) 15:41:21 INFO - [mozharness: 2016-08-22 22:41:21.598009Z] Running read-buildbot-config step. 15:41:21 INFO - Running pre-action listener: _resource_record_pre_action 15:41:21 INFO - Running main action method: read_buildbot_config 15:41:21 INFO - Using buildbot properties: 15:41:21 INFO - { 15:41:21 INFO - "project": "", 15:41:21 INFO - "product": "firefox", 15:41:21 INFO - "script_repo_revision": "production", 15:41:21 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:41:21 INFO - "repository": "", 15:41:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 15:41:21 INFO - "buildid": "20160822124915", 15:41:21 INFO - "pgo_build": "True", 15:41:21 INFO - "basedir": "/builds/slave/test", 15:41:21 INFO - "buildnumber": 484, 15:41:21 INFO - "slavename": "talos-linux64-ix-006", 15:41:21 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:41:21 INFO - "platform": "ubuntu64_hw", 15:41:21 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:41:21 INFO - "branch": "mozilla-aurora", 15:41:21 INFO - "repo_path": "releases/mozilla-aurora", 15:41:21 INFO - "revision": "07189ee916253fe9692528551ad1b96bc6c1bccc", 15:41:21 INFO - "stage_platform": "linux64-pgo", 15:41:21 INFO - "builduid": "63bc2500c2334edda63f0710f73a35f8", 15:41:21 INFO - "slavebuilddir": "test" 15:41:21 INFO - } 15:41:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 15:41:21 INFO - Running post-action listener: _resource_record_post_action 15:41:21 INFO - [mozharness: 2016-08-22 22:41:21.600467Z] Finished read-buildbot-config step (success) 15:41:21 INFO - [mozharness: 2016-08-22 22:41:21.600558Z] Running download-and-extract step. 15:41:21 INFO - Running pre-action listener: _resource_record_pre_action 15:41:21 INFO - Running main action method: download_and_extract 15:41:21 INFO - mkdir: /builds/slave/test/build/tests 15:41:21 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')]} 15:41:21 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:41:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:41:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:41:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:41:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:41:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:41:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:41:21 INFO - retry: Failed, sleeping 30 seconds before retrying 15:41:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 15:41:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:41:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:41:51 INFO - retry: Failed, sleeping 60 seconds before retrying 15:42:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 15:42:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:42:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:42:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 15:42:51 INFO - Caught exception: HTTP Error 404: Not Found 15:42:51 INFO - Caught exception: HTTP Error 404: Not Found 15:42:51 INFO - Caught exception: HTTP Error 404: Not Found 15:42:51 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:42:51 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:42:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:42:52 INFO - Downloaded 1361 bytes. 15:42:52 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:42:52 INFO - Using the following test package requirements: 15:42:52 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 15:42:52 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:42:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:42:52 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:42:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:42:52 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:42:52 INFO - u'jsshell-linux-x86_64.zip'], 15:42:52 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:42:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:42:52 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 15:42:52 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:42:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:42:52 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:42:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:42:52 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:42:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:42:52 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:42:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:42:52 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:42:52 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')]} 15:42:52 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:42:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:42:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:42:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:42:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:42:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:42:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:42:52 INFO - retry: Failed, sleeping 30 seconds before retrying 15:43:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:43:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:43:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:43:22 INFO - retry: Failed, sleeping 60 seconds before retrying 15:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:44:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:44:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 15:44:22 INFO - Caught exception: HTTP Error 404: Not Found 15:44:22 INFO - Caught exception: HTTP Error 404: Not Found 15:44:22 INFO - Caught exception: HTTP Error 404: Not Found 15:44:22 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:22 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:44:23 INFO - Downloaded 21767602 bytes. 15:44:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:44:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:44:24 INFO - Return code: 0 15:44:24 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:44:24 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')]} 15:44:24 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:44:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:44:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:44:24 INFO - retry: Failed, sleeping 30 seconds before retrying 15:44:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:44:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:44:54 INFO - retry: Failed, sleeping 60 seconds before retrying 15:45:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:45:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:45:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:45:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 15:45:54 INFO - Caught exception: HTTP Error 404: Not Found 15:45:54 INFO - Caught exception: HTTP Error 404: Not Found 15:45:54 INFO - Caught exception: HTTP Error 404: Not Found 15:45:54 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:45:54 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:45:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:45:55 INFO - Downloaded 21767602 bytes. 15:45:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:45:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:45:56 INFO - Return code: 0 15:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:56 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:45:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:45:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:45:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:45:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:45:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:45:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:45:56 INFO - retry: Failed, sleeping 30 seconds before retrying 15:46:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 15:46:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:46:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:46:26 INFO - retry: Failed, sleeping 60 seconds before retrying 15:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 15:47:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:47:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:47:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 15:47:26 INFO - Caught exception: HTTP Error 404: Not Found 15:47:26 INFO - Caught exception: HTTP Error 404: Not Found 15:47:26 INFO - Caught exception: HTTP Error 404: Not Found 15:47:26 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:47:26 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:47:27 INFO - Downloaded 14413723 bytes. 15:47:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:47:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:47:27 INFO - Return code: 0 15:47:27 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')]} 15:47:27 INFO - https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:47:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:47:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:47:27 INFO - retry: Failed, sleeping 30 seconds before retrying 15:47:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 15:47:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:47:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:47:57 INFO - retry: Failed, sleeping 60 seconds before retrying 15:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 15:48:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:48:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:48:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 15:48:57 INFO - Caught exception: HTTP Error 404: Not Found 15:48:57 INFO - Caught exception: HTTP Error 404: Not Found 15:48:57 INFO - Caught exception: HTTP Error 404: Not Found 15:48:57 INFO - trying https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:48:57 INFO - Downloading https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:48:59 INFO - Downloaded 63747869 bytes. 15:48:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:48:59 INFO - mkdir: /builds/slave/test/properties 15:48:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:48:59 INFO - Writing to file /builds/slave/test/properties/build_url 15:48:59 INFO - Contents: 15:48:59 INFO - build_url:https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:48:59 INFO - Running post-action listener: _resource_record_post_action 15:48:59 INFO - Running post-action listener: set_extra_try_arguments 15:48:59 INFO - [mozharness: 2016-08-22 22:48:59.857593Z] Finished download-and-extract step (success) 15:48:59 INFO - [mozharness: 2016-08-22 22:48:59.857827Z] Running populate-webroot step. 15:48:59 INFO - Running pre-action listener: _resource_record_pre_action 15:48:59 INFO - Running main action method: populate_webroot 15:48:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:48:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:48:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:48:59 INFO - '--tppagecycles', 15:48:59 INFO - '7'], 15:48:59 INFO - 'tests': ['tsvgm']}}, 15:48:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:48:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:48:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:48:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:48:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:59 INFO - 'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:59 INFO - 'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['damp', 'tps']}, 15:48:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:59 INFO - 'tests': ['damp', 'tps']}, 15:48:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['dromaeo_dom']}, 15:48:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:48:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['basic_compositor_video']}, 15:48:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:48:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other-e10s': {'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['a11yr', 15:48:59 INFO - 'ts_paint', 15:48:59 INFO - 'tpaint', 15:48:59 INFO - 'sessionrestore', 15:48:59 INFO - 'sessionrestore_no_auto_restore', 15:48:59 INFO - 'tabpaint']}, 15:48:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['tsvgx', 15:48:59 INFO - 'tsvgr_opacity', 15:48:59 INFO - 'tart', 15:48:59 INFO - 'tscrollx', 15:48:59 INFO - 'cart']}, 15:48:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:48:59 INFO - 'tsvgr_opacity', 15:48:59 INFO - 'tart', 15:48:59 INFO - 'tscrollx', 15:48:59 INFO - 'cart']}, 15:48:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:59 INFO - 'talos_options': ['--disable-e10s'], 15:48:59 INFO - 'tests': ['tp5o']}, 15:48:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:59 INFO - 'tests': ['tp5o']}, 15:48:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:59 INFO - 'talos_options': ['--disable-e10s', 15:48:59 INFO - '--xperf_path', 15:48:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:59 INFO - 'tests': ['tp5n']}, 15:48:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:59 INFO - 'talos_options': ['--xperf_path', 15:48:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:59 INFO - 'tests': ['tp5n']}}, 15:48:59 INFO - 'talos.zip': {'path': '', 15:48:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:48:59 INFO - Running post-action listener: _resource_record_post_action 15:48:59 INFO - [mozharness: 2016-08-22 22:48:59.876049Z] Finished populate-webroot step (success) 15:48:59 INFO - [mozharness: 2016-08-22 22:48:59.876139Z] Running create-virtualenv step. 15:48:59 INFO - Running pre-action listener: _resource_record_pre_action 15:48:59 INFO - Running main action method: create_virtualenv 15:48:59 INFO - Creating virtualenv /builds/slave/test/build/venv 15:48:59 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 15:48:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:48:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:48:59 INFO - Using real prefix '/usr' 15:48:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:49:00 INFO - Installing distribute.............................................................................................................................................................................................done. 15:49:02 INFO - Installing pip.................done. 15:49:02 INFO - Return code: 0 15:49:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:49:02 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')]} 15:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:02 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')]} 15:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:02 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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49:02 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 15:49:02 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 15:49:02 INFO - Using env: {'DISPLAY': ':0', 15:49:02 INFO - 'HOME': '/home/cltbld', 15:49:02 INFO - 'LANG': 'en_US.UTF-8', 15:49:02 INFO - 'LANGUAGE': 'en_US:en', 15:49:02 INFO - 'LOGNAME': 'cltbld', 15:49:02 INFO - 'MAIL': '/var/mail/cltbld', 15:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:02 INFO - 'MOZ_NO_REMOTE': '1', 15:49:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:02 INFO - 'NO_EM_RESTART': '1', 15:49:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:02 INFO - 'PWD': '/builds/slave/test', 15:49:02 INFO - 'SHELL': '/bin/bash', 15:49:02 INFO - 'SHLVL': '1', 15:49:02 INFO - 'TERM': 'linux', 15:49:02 INFO - 'TMOUT': '86400', 15:49:02 INFO - 'USER': 'cltbld', 15:49:02 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:02 INFO - '_': '/tools/buildbot/bin/python'} 15:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:02 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 15:49:02 INFO - Cleaning up... 15:49:02 INFO - Return code: 0 15:49:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:49:02 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')]} 15:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:02 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')]} 15:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:02 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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49:02 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 15:49:02 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 15:49:02 INFO - Using env: {'DISPLAY': ':0', 15:49:02 INFO - 'HOME': '/home/cltbld', 15:49:02 INFO - 'LANG': 'en_US.UTF-8', 15:49:02 INFO - 'LANGUAGE': 'en_US:en', 15:49:02 INFO - 'LOGNAME': 'cltbld', 15:49:02 INFO - 'MAIL': '/var/mail/cltbld', 15:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:02 INFO - 'MOZ_NO_REMOTE': '1', 15:49:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:02 INFO - 'NO_EM_RESTART': '1', 15:49:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:02 INFO - 'PWD': '/builds/slave/test', 15:49:02 INFO - 'SHELL': '/bin/bash', 15:49:02 INFO - 'SHLVL': '1', 15:49:02 INFO - 'TERM': 'linux', 15:49:02 INFO - 'TMOUT': '86400', 15:49:02 INFO - 'USER': 'cltbld', 15:49:02 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:02 INFO - '_': '/tools/buildbot/bin/python'} 15:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:02 INFO - Downloading/unpacking psutil>=3.1.1 15:49:02 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 15:49:02 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 15:49:02 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 15:49:02 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 15:49:02 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 15:49:02 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 15:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:49:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:04 INFO - Installing collected packages: psutil 15:49:04 INFO - Running setup.py install for psutil 15:49:04 INFO - building 'psutil._psutil_linux' extension 15:49:04 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 15:49:05 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 15:49:05 INFO - building 'psutil._psutil_posix' extension 15:49:05 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 15:49:05 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 15:49:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:05 INFO - Successfully installed psutil 15:49:05 INFO - Cleaning up... 15:49:05 INFO - Return code: 0 15:49:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:49:05 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')]} 15:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:05 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')]} 15:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:05 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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49:05 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 15:49:05 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 15:49:05 INFO - Using env: {'DISPLAY': ':0', 15:49:05 INFO - 'HOME': '/home/cltbld', 15:49:05 INFO - 'LANG': 'en_US.UTF-8', 15:49:05 INFO - 'LANGUAGE': 'en_US:en', 15:49:05 INFO - 'LOGNAME': 'cltbld', 15:49:05 INFO - 'MAIL': '/var/mail/cltbld', 15:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:05 INFO - 'MOZ_NO_REMOTE': '1', 15:49:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:05 INFO - 'NO_EM_RESTART': '1', 15:49:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:05 INFO - 'PWD': '/builds/slave/test', 15:49:05 INFO - 'SHELL': '/bin/bash', 15:49:05 INFO - 'SHLVL': '1', 15:49:05 INFO - 'TERM': 'linux', 15:49:05 INFO - 'TMOUT': '86400', 15:49:05 INFO - 'USER': 'cltbld', 15:49:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:05 INFO - '_': '/tools/buildbot/bin/python'} 15:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:49:05 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 15:49:05 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 15:49:05 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 15:49:05 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 15:49:05 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 15:49:05 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 15:49:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:49:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:49:07 INFO - Installing collected packages: mozsystemmonitor 15:49:07 INFO - Running setup.py install for mozsystemmonitor 15:49:07 INFO - Successfully installed mozsystemmonitor 15:49:07 INFO - Cleaning up... 15:49:07 INFO - Return code: 0 15:49:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:49: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')]} 15:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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')]} 15:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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', '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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:49: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 jsonschema==2.5.1 15:49:07 INFO - Using env: {'DISPLAY': ':0', 15:49:07 INFO - 'HOME': '/home/cltbld', 15:49:07 INFO - 'LANG': 'en_US.UTF-8', 15:49:07 INFO - 'LANGUAGE': 'en_US:en', 15:49:07 INFO - 'LOGNAME': 'cltbld', 15:49:07 INFO - 'MAIL': '/var/mail/cltbld', 15:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:07 INFO - 'MOZ_NO_REMOTE': '1', 15:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:07 INFO - 'NO_EM_RESTART': '1', 15:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:07 INFO - 'PWD': '/builds/slave/test', 15:49:07 INFO - 'SHELL': '/bin/bash', 15:49:07 INFO - 'SHLVL': '1', 15:49:07 INFO - 'TERM': 'linux', 15:49:07 INFO - 'TMOUT': '86400', 15:49:07 INFO - 'USER': 'cltbld', 15:49:07 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:07 INFO - '_': '/tools/buildbot/bin/python'} 15:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:08 INFO - Downloading/unpacking jsonschema==2.5.1 15:49:08 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 15:49:08 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 15:49:08 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 15:49:08 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 15:49:08 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 15:49:08 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 15:49:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:49:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:49: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 15:49: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 15:49: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 15:49: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 15:49: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 15:49: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 15:49:10 INFO - Downloading functools32-3.2.3-2.tar.gz 15:49:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:49:10 INFO - warning: no files found matching '*.txt' 15:49:10 INFO - no previously-included directories found matching 'build' 15:49:10 INFO - no previously-included directories found matching 'dist' 15:49:10 INFO - no previously-included directories found matching '.git*' 15:49:10 INFO - Installing collected packages: jsonschema, functools32 15:49:10 INFO - Running setup.py install for functools32 15:49:10 INFO - warning: no files found matching '*.txt' 15:49:10 INFO - no previously-included directories found matching 'build' 15:49:10 INFO - no previously-included directories found matching 'dist' 15:49:10 INFO - no previously-included directories found matching '.git*' 15:49:10 INFO - Successfully installed jsonschema functools32 15:49:10 INFO - Cleaning up... 15:49:10 INFO - Return code: 0 15:49:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:49:10 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')]} 15:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:10 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')]} 15:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:10 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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49:10 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 15:49:10 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 15:49:10 INFO - Using env: {'DISPLAY': ':0', 15:49:10 INFO - 'HOME': '/home/cltbld', 15:49:10 INFO - 'LANG': 'en_US.UTF-8', 15:49:10 INFO - 'LANGUAGE': 'en_US:en', 15:49:10 INFO - 'LOGNAME': 'cltbld', 15:49:10 INFO - 'MAIL': '/var/mail/cltbld', 15:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:10 INFO - 'MOZ_NO_REMOTE': '1', 15:49:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:10 INFO - 'NO_EM_RESTART': '1', 15:49:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:10 INFO - 'PWD': '/builds/slave/test', 15:49:10 INFO - 'SHELL': '/bin/bash', 15:49:10 INFO - 'SHLVL': '1', 15:49:10 INFO - 'TERM': 'linux', 15:49:10 INFO - 'TMOUT': '86400', 15:49:10 INFO - 'USER': 'cltbld', 15:49:10 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:10 INFO - '_': '/tools/buildbot/bin/python'} 15:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:49:10 INFO - Cleaning up... 15:49:10 INFO - Return code: 0 15:49:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:49:10 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')]} 15:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:10 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')]} 15:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:10 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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49:10 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 15:49:10 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 15:49:10 INFO - Using env: {'DISPLAY': ':0', 15:49:10 INFO - 'HOME': '/home/cltbld', 15:49:10 INFO - 'LANG': 'en_US.UTF-8', 15:49:10 INFO - 'LANGUAGE': 'en_US:en', 15:49:10 INFO - 'LOGNAME': 'cltbld', 15:49:10 INFO - 'MAIL': '/var/mail/cltbld', 15:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:10 INFO - 'MOZ_NO_REMOTE': '1', 15:49:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:10 INFO - 'NO_EM_RESTART': '1', 15:49:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:10 INFO - 'PWD': '/builds/slave/test', 15:49:10 INFO - 'SHELL': '/bin/bash', 15:49:10 INFO - 'SHLVL': '1', 15:49:10 INFO - 'TERM': 'linux', 15:49:10 INFO - 'TMOUT': '86400', 15:49:10 INFO - 'USER': 'cltbld', 15:49:10 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:10 INFO - '_': '/tools/buildbot/bin/python'} 15:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:10 INFO - Downloading/unpacking blobuploader==1.2.4 15:49:10 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 15:49:10 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 15:49:10 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 15:49:10 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 15:49:10 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 15:49:10 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 15:49:12 INFO - Downloading blobuploader-1.2.4.tar.gz 15:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:49:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:49: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 15:49: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 15:49: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 15:49: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 15:49: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 15:49: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 15:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:49:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:49:13 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 15:49:13 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 15:49:13 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 15:49:13 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 15:49:13 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 15:49:13 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 15:49:13 INFO - Downloading docopt-0.6.1.tar.gz 15:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:49:13 INFO - Installing collected packages: blobuploader, requests, docopt 15:49:13 INFO - Running setup.py install for blobuploader 15:49:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:49:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:49:13 INFO - Running setup.py install for requests 15:49:13 INFO - Running setup.py install for docopt 15:49:14 INFO - Successfully installed blobuploader requests docopt 15:49:14 INFO - Cleaning up... 15:49:14 INFO - Return code: 0 15:49:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:14 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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49:14 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 15:49:14 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 15:49:14 INFO - Using env: {'DISPLAY': ':0', 15:49:14 INFO - 'HOME': '/home/cltbld', 15:49:14 INFO - 'LANG': 'en_US.UTF-8', 15:49:14 INFO - 'LANGUAGE': 'en_US:en', 15:49:14 INFO - 'LOGNAME': 'cltbld', 15:49:14 INFO - 'MAIL': '/var/mail/cltbld', 15:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:14 INFO - 'MOZ_NO_REMOTE': '1', 15:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:14 INFO - 'NO_EM_RESTART': '1', 15:49:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:14 INFO - 'PWD': '/builds/slave/test', 15:49:14 INFO - 'SHELL': '/bin/bash', 15:49:14 INFO - 'SHLVL': '1', 15:49:14 INFO - 'TERM': 'linux', 15:49:14 INFO - 'TMOUT': '86400', 15:49:14 INFO - 'USER': 'cltbld', 15:49:14 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:14 INFO - '_': '/tools/buildbot/bin/python'} 15:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:14 INFO - Running setup.py (path:/tmp/pip-U_7DWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:14 INFO - Running setup.py (path:/tmp/pip-WByepB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:14 INFO - Running setup.py (path:/tmp/pip-Idb33O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:14 INFO - Running setup.py (path:/tmp/pip-Fs5BDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:14 INFO - Running setup.py (path:/tmp/pip-pOj_EX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:14 INFO - Running setup.py (path:/tmp/pip-inPZCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:14 INFO - Running setup.py (path:/tmp/pip-9LywDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:14 INFO - Running setup.py (path:/tmp/pip-LxWpfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:14 INFO - Running setup.py (path:/tmp/pip-TA4Trh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:15 INFO - Running setup.py (path:/tmp/pip-jP6Xnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:15 INFO - Running setup.py (path:/tmp/pip-z_Iuny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:15 INFO - Running setup.py (path:/tmp/pip-TClLFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:15 INFO - Running setup.py (path:/tmp/pip-9XwalH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:15 INFO - Running setup.py (path:/tmp/pip-xAwnoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:15 INFO - Running setup.py (path:/tmp/pip-A7YABR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:15 INFO - Running setup.py (path:/tmp/pip-4wSfpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:15 INFO - Running setup.py (path:/tmp/pip-Q5irnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:49:15 INFO - Running setup.py install for manifestparser 15:49:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:49:15 INFO - Running setup.py install for mozcrash 15:49:15 INFO - Running setup.py install for mozdebug 15:49:15 INFO - Running setup.py install for mozdevice 15:49:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Running setup.py install for mozfile 15:49:16 INFO - Running setup.py install for mozhttpd 15:49:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Running setup.py install for mozinfo 15:49:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Running setup.py install for mozInstall 15:49:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Running setup.py install for mozleak 15:49:16 INFO - Running setup.py install for mozlog 15:49:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Running setup.py install for moznetwork 15:49:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Running setup.py install for mozprocess 15:49:16 INFO - Running setup.py install for mozprofile 15:49:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:49:16 INFO - Running setup.py install for mozrunner 15:49:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:49:17 INFO - Running setup.py install for mozscreenshot 15:49:17 INFO - Running setup.py install for moztest 15:49:17 INFO - Running setup.py install for mozversion 15:49:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:49:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:49:17 INFO - Cleaning up... 15:49:17 INFO - Return code: 0 15:49:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:17 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')]} 15:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:17 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')]} 15:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:17 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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49:17 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 15:49:17 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 15:49:17 INFO - Using env: {'DISPLAY': ':0', 15:49:17 INFO - 'HOME': '/home/cltbld', 15:49:17 INFO - 'LANG': 'en_US.UTF-8', 15:49:17 INFO - 'LANGUAGE': 'en_US:en', 15:49:17 INFO - 'LOGNAME': 'cltbld', 15:49:17 INFO - 'MAIL': '/var/mail/cltbld', 15:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:17 INFO - 'MOZ_NO_REMOTE': '1', 15:49:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:17 INFO - 'NO_EM_RESTART': '1', 15:49:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:17 INFO - 'PWD': '/builds/slave/test', 15:49:17 INFO - 'SHELL': '/bin/bash', 15:49:17 INFO - 'SHLVL': '1', 15:49:17 INFO - 'TERM': 'linux', 15:49:17 INFO - 'TMOUT': '86400', 15:49:17 INFO - 'USER': 'cltbld', 15:49:17 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:17 INFO - '_': '/tools/buildbot/bin/python'} 15:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:17 INFO - Running setup.py (path:/tmp/pip-wXEt3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:17 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)) 15:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:17 INFO - Running setup.py (path:/tmp/pip-NnZhkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:17 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)) 15:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:17 INFO - Running setup.py (path:/tmp/pip-BczQub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:17 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)) 15:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:17 INFO - Running setup.py (path:/tmp/pip-LqFnVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:17 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)) 15:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:17 INFO - Running setup.py (path:/tmp/pip-Q_Asqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:17 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)) 15:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:17 INFO - Running setup.py (path:/tmp/pip-ZbGIPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:18 INFO - Running setup.py (path:/tmp/pip-mztwwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:18 INFO - Running setup.py (path:/tmp/pip-TFvD18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:18 INFO - Running setup.py (path:/tmp/pip-8sGN25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:18 INFO - Running setup.py (path:/tmp/pip-qK3SiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:18 INFO - Running setup.py (path:/tmp/pip-VnWwWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:18 INFO - Running setup.py (path:/tmp/pip-3AORDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:18 INFO - Running setup.py (path:/tmp/pip-ZOzb2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:18 INFO - Running setup.py (path:/tmp/pip-kpOoqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:18 INFO - Running setup.py (path:/tmp/pip-serxH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:18 INFO - Running setup.py (path:/tmp/pip-jQrWPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:18 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)) 15:49:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:18 INFO - Running setup.py (path:/tmp/pip-uydGzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:18 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)) 15:49:18 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)) 15:49:18 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)) 15:49:18 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)) 15:49:18 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)) 15:49:18 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)) 15:49:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49:18 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 15:49:18 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 15:49:18 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 15:49:18 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 15:49:18 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 15:49:18 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 15:49:20 INFO - Downloading blessings-1.6.tar.gz 15:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:49:20 INFO - Installing collected packages: blessings 15:49:20 INFO - Running setup.py install for blessings 15:49:20 INFO - Successfully installed blessings 15:49:20 INFO - Cleaning up... 15:49:20 INFO - Return code: 0 15:49:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:49:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:21 INFO - Reading from file tmpfile_stdout 15:49:21 INFO - Current package versions: 15:49:21 INFO - argparse == 1.2.1 15:49:21 INFO - blessings == 1.6 15:49:21 INFO - blobuploader == 1.2.4 15:49:21 INFO - docopt == 0.6.1 15:49:21 INFO - functools32 == 3.2.3-2 15:49:21 INFO - jsonschema == 2.5.1 15:49:21 INFO - manifestparser == 1.1 15:49:21 INFO - mozInstall == 1.12 15:49:21 INFO - mozcrash == 0.17 15:49:21 INFO - mozdebug == 0.1 15:49:21 INFO - mozdevice == 0.48 15:49:21 INFO - mozfile == 1.2 15:49:21 INFO - mozhttpd == 0.7 15:49:21 INFO - mozinfo == 0.9 15:49:21 INFO - mozleak == 0.1 15:49:21 INFO - mozlog == 3.3 15:49:21 INFO - moznetwork == 0.27 15:49:21 INFO - mozprocess == 0.23 15:49:21 INFO - mozprofile == 0.28 15:49:21 INFO - mozrunner == 6.12 15:49:21 INFO - mozscreenshot == 0.1 15:49:21 INFO - mozsystemmonitor == 0.3 15:49:21 INFO - moztest == 0.7 15:49:21 INFO - mozversion == 1.4 15:49:21 INFO - psutil == 3.1.1 15:49:21 INFO - requests == 1.2.3 15:49:21 INFO - wsgiref == 0.1.2 15:49:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:21 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')]} 15:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:21 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')]} 15:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:21 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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49:21 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 15:49:21 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 15:49:21 INFO - Using env: {'DISPLAY': ':0', 15:49:21 INFO - 'HOME': '/home/cltbld', 15:49:21 INFO - 'LANG': 'en_US.UTF-8', 15:49:21 INFO - 'LANGUAGE': 'en_US:en', 15:49:21 INFO - 'LOGNAME': 'cltbld', 15:49:21 INFO - 'MAIL': '/var/mail/cltbld', 15:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:21 INFO - 'MOZ_NO_REMOTE': '1', 15:49:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:21 INFO - 'NO_EM_RESTART': '1', 15:49:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:21 INFO - 'PWD': '/builds/slave/test', 15:49:21 INFO - 'SHELL': '/bin/bash', 15:49:21 INFO - 'SHLVL': '1', 15:49:21 INFO - 'TERM': 'linux', 15:49:21 INFO - 'TMOUT': '86400', 15:49:21 INFO - 'USER': 'cltbld', 15:49:21 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:21 INFO - '_': '/tools/buildbot/bin/python'} 15:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:21 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)) 15:49:21 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)) 15:49:21 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)) 15:49:21 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)) 15:49:21 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)) 15:49:21 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)) 15:49:21 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)) 15:49:21 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)) 15:49:21 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)) 15:49:21 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)) 15:49:21 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)) 15:49:21 INFO - Cleaning up... 15:49:21 INFO - Return code: 0 15:49:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:49:21 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')]} 15:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:21 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')]} 15:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:21 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 0x20b8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2379180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', '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 15:49:21 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 15:49:21 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 15:49:21 INFO - Using env: {'DISPLAY': ':0', 15:49:21 INFO - 'HOME': '/home/cltbld', 15:49:21 INFO - 'LANG': 'en_US.UTF-8', 15:49:21 INFO - 'LANGUAGE': 'en_US:en', 15:49:21 INFO - 'LOGNAME': 'cltbld', 15:49:21 INFO - 'MAIL': '/var/mail/cltbld', 15:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:21 INFO - 'MOZ_NO_REMOTE': '1', 15:49:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:21 INFO - 'NO_EM_RESTART': '1', 15:49:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:21 INFO - 'PWD': '/builds/slave/test', 15:49:21 INFO - 'SHELL': '/bin/bash', 15:49:21 INFO - 'SHLVL': '1', 15:49:21 INFO - 'TERM': 'linux', 15:49:21 INFO - 'TMOUT': '86400', 15:49:21 INFO - 'USER': 'cltbld', 15:49:21 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:21 INFO - '_': '/tools/buildbot/bin/python'} 15:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:49:21 INFO - Cleaning up... 15:49:21 INFO - Return code: 0 15:49:21 INFO - Running post-action listener: _resource_record_post_action 15:49:21 INFO - Running post-action listener: _start_resource_monitoring 15:49:21 INFO - Starting resource monitoring. 15:49:21 INFO - [mozharness: 2016-08-22 22:49:21.676780Z] Finished create-virtualenv step (success) 15:49:21 INFO - [mozharness: 2016-08-22 22:49:21.677316Z] Running install step. 15:49:21 INFO - Running pre-action listener: _resource_record_pre_action 15:49:21 INFO - Running main action method: install 15:49:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:21 INFO - Reading from file tmpfile_stdout 15:49:21 INFO - Detecting whether we're running mozinstall >=1.0... 15:49:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:49:21 INFO - Reading from file tmpfile_stdout 15:49:21 INFO - Output received: 15:49:21 INFO - Usage: mozinstall [options] installer 15:49:21 INFO - Options: 15:49:21 INFO - -h, --help show this help message and exit 15:49:21 INFO - -d DEST, --destination=DEST 15:49:21 INFO - Directory to install application into. [default: 15:49:21 INFO - "/builds/slave/test"] 15:49:21 INFO - --app=APP Application being installed. [default: firefox] 15:49:21 INFO - mkdir: /builds/slave/test/build/application 15:49:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:49:38 INFO - Reading from file tmpfile_stdout 15:49:38 INFO - Output received: 15:49:38 INFO - /builds/slave/test/build/application/firefox/firefox 15:49:38 INFO - Running post-action listener: _resource_record_post_action 15:49:38 INFO - [mozharness: 2016-08-22 22:49:38.855567Z] Finished install step (success) 15:49:38 INFO - [mozharness: 2016-08-22 22:49:38.855846Z] Running run-tests step. 15:49:38 INFO - Running pre-action listener: _resource_record_pre_action 15:49:38 INFO - Running main action method: run_tests 15:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:38 INFO - Python 2.7.3 15:49:38 INFO - Return code: 0 15:49:38 INFO - grabbing minidump binary from tooltool 15:49: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')]} 15:49: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 0x23773b0>, '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 0x23782b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2378740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49: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 15:49: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 15:49: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 15:49:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:38 INFO - Return code: 0 15:49:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:49:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:49:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 15:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 15:49:38 INFO - Using env: {'DISPLAY': ':0', 15:49:38 INFO - 'HOME': '/home/cltbld', 15:49:38 INFO - 'LANG': 'en_US.UTF-8', 15:49:38 INFO - 'LANGUAGE': 'en_US:en', 15:49:38 INFO - 'LOGNAME': 'cltbld', 15:49:38 INFO - 'MAIL': '/var/mail/cltbld', 15:49:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:38 INFO - 'MOZ_NO_REMOTE': '1', 15:49:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:38 INFO - 'NO_EM_RESTART': '1', 15:49:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:38 INFO - 'PWD': '/builds/slave/test', 15:49:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:38 INFO - 'SHELL': '/bin/bash', 15:49:38 INFO - 'SHLVL': '1', 15:49:38 INFO - 'TERM': 'linux', 15:49:38 INFO - 'TMOUT': '86400', 15:49:38 INFO - 'USER': 'cltbld', 15:49:38 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942', 15:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:38 INFO - '_': '/tools/buildbot/bin/python'} 15:49:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 15:49:39 INFO - mozversion application_buildid: 20160822124915 15:49:39 INFO - mozversion application_changeset: 07189ee916253fe9692528551ad1b96bc6c1bccc 15:49:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:49:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:39 INFO - mozversion application_name: Firefox 15:49:39 INFO - mozversion application_remotingname: firefox-dev 15:49:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:49:39 INFO - mozversion application_vendor: Mozilla 15:49:39 INFO - mozversion application_version: 50.0a2 15:49:39 INFO - mozversion platform_buildid: 20160822124915 15:49:39 INFO - mozversion platform_changeset: 07189ee916253fe9692528551ad1b96bc6c1bccc 15:49:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:49:39 INFO - mozversion platform_version: 50.0a2 15:49:39 INFO - using testdate: 1471906179 15:49:39 INFO - actual date: 1471906179 15:49:39 INFO - starting webserver on 'localhost:34016' 15:49:39 INFO - SUITE-START | Running 2 tests 15:49:39 INFO - TEST-START | dromaeo_css 15:49:39 INFO - operating with platform_type : linux_ 15:49:39 INFO - Initialising browser for dromaeo_css test... 15:49:39 INFO - TEST-INFO | started process 20931 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3JRBdE/profile http://localhost:34016/getInfo.html) 15:49:43 INFO - PROCESS | 20931 | __metrics Screen width/height:1600/1200 15:49:43 INFO - PROCESS | 20931 | colorDepth:24 15:49:43 INFO - PROCESS | 20931 | Browser inner width/height: 1024/697 15:49:43 INFO - PROCESS | 20931 | __metrics 15:49:43 INFO - PROCESS | 20931 | 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" 15:49:43 INFO - PROCESS | 20931 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:49:44 INFO - PROCESS | 20931 | console.error: PushService: 15:49:44 INFO - PROCESS | 20931 | stateChangeProcessEnqueue: Error transitioning state 15:49:44 INFO - PROCESS | 20931 | UnknownError 15:49:44 INFO - TEST-INFO | 20931: exit 0 15:49:44 INFO - Browser initialized. 15:49:44 INFO - Running cycle 1/1 for dromaeo_css test... 15:49:44 INFO - TEST-INFO | started process 21020 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3JRBdE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:56 INFO - PROCESS | 21020 | RSS: Main: 145690624 15:49:56 INFO - PROCESS | 21020 | 15:50:47 INFO - PROCESS | 21020 | 1471906247637 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] 15:52:56 INFO - PROCESS | 21020 | Cycle 1(1): loaded http://localhost:34016/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34016/tests/dromaeo/cssquery-ext.html) 15:52:57 INFO - PROCESS | 21020 | RSS: Main: 221364224 15:52:57 INFO - PROCESS | 21020 | 15:56:00 INFO - PROCESS | 21020 | Cycle 1(1): loaded http://localhost:34016/tests/dromaeo/cssquery-ext.html (next: http://localhost:34016/tests/dromaeo/cssquery-jquery.html) 15:56:00 INFO - PROCESS | 21020 | RSS: Main: 232718336 15:56:00 INFO - PROCESS | 21020 | 15:59:02 INFO - PROCESS | 21020 | Cycle 1(1): loaded http://localhost:34016/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34016/tests/dromaeo/cssquery-mootools.html) 15:59:03 INFO - PROCESS | 21020 | RSS: Main: 219594752 15:59:03 INFO - PROCESS | 21020 | 16:02:05 INFO - PROCESS | 21020 | Cycle 1(1): loaded http://localhost:34016/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34016/tests/dromaeo/cssquery-prototype.html) 16:02:05 INFO - PROCESS | 21020 | RSS: Main: 174317568 16:02:05 INFO - PROCESS | 21020 | 16:05:08 INFO - PROCESS | 21020 | Cycle 1(1): loaded http://localhost:34016/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34016/tests/dromaeo/cssquery-yui.html) 16:05:09 INFO - PROCESS | 21020 | RSS: Main: 176553984 16:05:09 INFO - PROCESS | 21020 | 16:08:11 INFO - PROCESS | 21020 | Cycle 1(1): loaded http://localhost:34016/tests/dromaeo/cssquery-yui.html (next: http://localhost:34016/tests/dromaeo/cssquery-dojo.html) 16:08:12 INFO - PROCESS | 21020 | RSS: Main: 265252864 16:08:12 INFO - PROCESS | 21020 | 16:08:12 INFO - PROCESS | 21020 | __start_tp_report 16:08:12 INFO - PROCESS | 21020 | _x_x_mozilla_page_load 16:08:12 INFO - PROCESS | 21020 | _x_x_mozilla_page_load_details 16:08:12 INFO - PROCESS | 21020 | |i|pagename|runs| 16:08:12 INFO - PROCESS | 21020 | |0;dojo.html;3222.5327327537507;3256.478963365862;3262.265990152215;3281.1469018055327;3303.322818822142;5635.211070450135;5744.253247077884;5757.625754325966;5781.595288329819;5783.884322313556;5598.384177740445;5651.5761317901215;5653.971730141344;5655.4061823508555;5660.094384898417;5044.369453818273;5087;5106.617003724717;5119;5155.58755299577;5153.56194723449;5200.141976573861;5204.817831375903;5205.0370681423865;5212.400573934008;5060.39275286966;5080.872978175556;5091.439941606421;5121.795128194886;5135.871603209912;5164.147915593922;5177.896442071156;5185.507376799198;5188.377394712658;5207.505286997729;5027.396712394536;5099.898002039939;5100.464451232641;5101.591872650179;5151.845444636667;5578.944210557883;5666.716664166775;5669.121286200645;5674.375818659944;5706.28671416073;6168.259808822925;6181.690915454209;6184.041473571604;6184.381561843825;6185.628862268279;5984.311804142536;6075.635461872302;6117.918752924055;6137.2635283765785;6215.2541694996435;5740.712964351766;5783;5797.188393624896;5805.593608447452;5810.448007439329;6305.211848518935;6317.494600432046;6321.1150438938585;6322.430981211713;6323.146375239284;5878.853028674317;5975.073863551156;6009.098635204667;6037.879242415127;6109.389061093855;6130.356312587203;6165.0444181152;6165.876682466415;6166.075088736726;6170.290416602058;6006.219191505076;6061.787837425669;6062.878742425127;6067.969660151671;6084.087386892002;5864.589478736533;6020;6076.149339092531;6087.026075827935;6142.170806941095;4644.140833945731;4672.159011377985;4681.133990211822;4684.976575117103;4710.52894710533;4153.273177193979;4171.0615111599645;4172.520160181557;4187.6440502557625;4197.685173611931;5659.915101273484;5751.166080918243;5841.4450627190345;5849.356570777211;5917.467427931507;5980.073088671263;5991.820245392645;5997.970010149921;5999.700014999233;6026.487748541423;5643.181738647955;5664;5674.659520428788;5677.829665110054;5682.602217844711;5106.591472682177;5259.973700131475;5296.09966305729;5306.495882891119;5373.650712703662;6387.169667943138;6415.3263907289065;6417.358264173638;6418.646974416265;6427.550071495044;6163.722632481638;6177.413145751147;6228.781992630236;6233.283172435046;6242.750289988349;6057.121717351048;6234.438900498977;6286.937130628635;6299.527535434769;6359.554831161774;6059.848503787441;6064.818055458167;6065.968785306421;6067.393260674074;6088.908666370096;6152.261728592597;6154.753809847735;6159.168512250789;6160.199174107516;6161.691915404302;5706.3152421709665;5858.326292476419;5884.911291411103;5901.439363260483;5973;5889.057750759858;5918.585699001029;5925.674087925033;5933.614315069507;5939.346671866005;5796.420357964338;5849.824505264849;5858.121281807763;5862.032764593794;5864.06175011996;5872.29532456108;6005.669688167192;6085.9695701521205;6108;6115.696722738907;5944.256967879015;5944.73248703818;5945.732442040205;5948.37542058095;5973.253343332083 16:08:12 INFO - PROCESS | 21020 | |1;ext.html;607.7568972411035;638.5944924972641;665.5208250059958;666.3569655282652;669.8660267946409;34829.65170348296;37582.24835503292;37916.05209869751;49683.75158124213;50653.74673126629;48835.26747098788;49093;49259.50740492594;49266.50733492664;49334.753326233316;9292.349535532516;9434.245260379188;9440.952795236015;9448.055194480532;9451.432914025181;8541.701040463586;10815.134789216843;11055.944720276428;11080.778384432306;11546.71133221672;11447.771044579104;11543.076553875711;11551.364674942875;11553.30680159193;11561.364124973123;8600.784980375478;11264.943675281613;11301.152413569016;11338.943305283503;11343.943280283545;10832.133429325713;11162;11181.161412894066;11551.595694150748;11568.710782230512;45777;46151.5384846154;46224.768876155744;46231.30653040207;46288.53711462876;3853.7495062820826;4000.0399904023116;4107.014316564033;4296.806643701041;4589.931151032737;34666.30667386663;34833.129171770655;34955.1261218469;35264.647353526394;35870.48821666557;4741.620670346364;4741.668083234175;4786.880327991793;4826;4864.854054378375;396888;399761;400225;401257.9937100325;401992;63485.36514634795;63809.68095159494;63810.72378552403;63835.6808215956;63926;39734.60265397367;40644;40679.59320406817;42643.36034959478;42722;16930.915345423193;17089.829101709074;17102.40141595063;17114.914425427793;17138.228779704943;12665.556705515262;13014.544490942772;13392.196468211743;13413.128146670435;13525;20456.69314960277;21068.47328816792;21088.261910833047;21842.453938651342;21885.781142188374;7416.3696085832225;7474.1404738454685;7508;7609.429292803062;7644.159142494321;1068.8076146293586;1092.4592326798127;1150.5455345138623;1153.382940127028;1190.3691043746828;35141.43759518648;35584.11471595643;36086.097847553494;36287.4556881647;38846.41730374047;1198.7302856857136;1201.567435723139;1205.5659962413524;1227.6746662134542;1230.464747834677;17822.10889455548;18149.833302601834;18216.180271887795;18226.63546729058;18338.724919126224;626748;639459;640316;640837;641381;61207;61639;61745.38254617396;61931.690341548005;61941.69029154915;1369;1451.7314296855116;1464.282501574221;1469.6987117640858;1506.691128318692;33888.322233555686;35767;35777.82111089428;35838.820805896845;35843.28313433665;52981.205281921575;53480;53596.732016339665;53644.19533706919;53705.46294537005;482366;482611;486274;486302;487645;30183.849080754455;30960.53559196659;31749.36501270008;31767.205819854687;32896;51741.7412912933;51985;52349;52453.7377313111;53394.73302633462;43083.35374969315;43252.783736082376;43531.78234108809;43834.56165438305;44342.77828610966;43217.24610292928;44644.77677611591;44692.32961505645;44892.55107448884;45365;43719;45277.547224527334;46772;46873.06253874971;48111.75944120257;37729;40317.79841100776;40733.79633101816;40887.795561022;41708.79145604253;29103.41793164167;30253.394932101673;30497.847510763193;31152;32195 16:08:12 INFO - PROCESS | 21020 | |2;jquery.html;402.7313961022776;412.92360913231056;422.6513126670496;429.34310504927464;437.89490522274644;15936.920315398422;20201.39395818126;21781;21832.781672183293;22086.11655533777;20266.797332026712;20838.583228335425;20932.581348373024;20971.37085887422;21040.158393664235;5828.562857785661;5848;6045.093236014589;6047.42549457803;6073.271207455112;5906.05503119501;5988.640681559121;6003.429674180946;6043.6675982820925;6045.972184728606;5762.884742305151;5835.5915085944;5875;6005.849853753647;6025.728842202111;5827.300723913115;5845.941540584604;5866.325372582122;5872.530197584183;5906.5570082244;5296.814611488579;5405.675659460406;5518.034343989786;5550.111982082888;5619.409961953977;14519.056261343085;14762.114273143647;16503;23938.760612393828;24196.637050444257;2452.889619967106;2482.1932871816553;2497.225859983411;2683.5169669459488;2720.088770261945;10255.282130250886;12196.695082622917;15330.386784528606;15569.688606227812;15904.920475397665;1990.3033938121541;1991.2731852873742;2003.7896020917885;2025.9392218233477;2035.562354093862;87877.56061219717;88010;88055.55972220162;88523.55738221332;90552;10555.84166237507;10594.205434592437;11949.342786146672;12024.699382515508;12118.39408029595;16398.67202655964;16728.16359182036;16797.160141992852;16808.411705590246;16820.0748958806;8843.60203790831;8870.86693699595;8910.108989101167;8920;8937.553122343857;8587.699430519902;8618.267447266928;8622.741317760478;8813.691520796741;8846.115388461103;4853;4879.219324908068;5142.460041695643;5181.637285390062;5193.1171700810955;2086.1759604955955;2101.8003289687454;2111.809937105668;2145.0668959002883;2174.2824867793906;1055.9102476289602;1059.4120263253815;1088.6679562733284;1110.3837370259585;1165.7377090154816;20179.192832286542;21169.470763230733;21336;21342;21348.78651213499;1025.7281820317621;1074.7313171707074;1095.1074873977682;1103.0679076180616;1105.4528008635616;11308.208425410143;11803.468843902045;11865.5847045353;11911.523539058513;11987.700307492209;382494;383428;387588;534902;543997;101407.49296253471;105320;107620;108120;109219.45390272998;1051.51104736298;1070.1866581398194;1098.1215027977587;1104.6520346091115;1107.9723006924892;14381.64045898861;17871.28514859391;18037.909810450863;19115.47838349158;19200.61598768045;33165.33669326648;33372;45168.77415612901;45170.322445164005;45824.770876145405;55655.721721391135;61410;62334;62652.06021909584;64693;8492.830143397221;8858.73423797287;8908.198262156437;8908.821823563621;9306.208972237166;10559.683209503728;12849.164804287691;13059.934700326437;13295.734085318432;13328.533501327407;11545.711357216136;11801.40992950332;11874.109441791727;12111.697207569881;12208.023358131506;11742;11918.40407979615;12512.43694033789;12855.614331570068;12864.614061578168;11890.702732431413;12623.684407889876;12813.615591532269;13298.335083245993;13446.327683615977;7886.72396466121;8479.95760021196;9129.269658427444;9148.130927561871;9453;11385.316880987168;12148.939255303667;12216.877831221575;12356.135070545335;12472.376381180724 16:08:12 INFO - PROCESS | 21020 | |3;mootools.html;1544.5906834688803;1609.356257497001;1821.1895706410644;1900.1948149258208;1958.265650381107;9471.337006409545;10254.02586754259;10261.640842570512;10263.743406414844;10267.075963163314;8765.561721913895;9175.95412022939;9336.906630933689;9394.765130871732;10023.649172278972;2553.9233822985293;2587.3014286142825;2590.2358804152727;2597.0390955346547;2600.45390468001;2493.463905260369;2526.406294520795;2546.987265063672;2552.221572420411;2553.5658937980493;2553.438243586408;2559.11710459892;2585.1598230575046;2587.2109006752935;2602.908898188564;2496.0889275414524;2517.471331020479;2525.9621105683427;2530.02594001309;2530.304166354249;2498.487809998946;2498.537770512461;2503.3616427810794;2508.2851387354517;2534.835235709673;10066.194704423628;10266.640667576674;10269.640562580274;10276.023777741102;10283.382997020202;5903.49820265278;6137.3862613738265;6137.539684523634;6146.416090471398;6148.8770224595255;3751.268502641986;3835.1946091320856;3916.0405700603533;3921.5294164700135;3930.7051971101996;1264.0772236267576;1304.4651692805903;1319.5183757928382;1327.2434712214042;1342.449595665782;14558.417663293456;14623.122612643276;14630.41478340865;14639;14671.26643667823;5119.360079990001;5253.4221235664045;5386.030514507426;5408.1887716842;5417.18742188667;12031.217970832044;12046.638600841989;12046.75906481883;12047.75904481923;12060.155789094679;5960.344362120163;5963.314218864799;5965;5969.074793407029;5969.701514924236;4859.1010663027455;5095.8726031848755;5175.948240517547;5229.764660590283;5441.211024401518;4467.977660111744;4511.548845115528;4521.751303678329;4524.502304746476;4619.214733495314;1448.3554818106052;1457.0529156048653;1473.572663927449;1479.3934486860335;1480.2006916265311;957.2150836314295;1026.5637104230673;1050.3120456101267;1051.1590727418188;1062.8618279623754;6181.289151747606;6347.333529979378;6405.167328247298;6463.7091330890225;6597.86804263912;518.1631664861297;519.7011718262014;521.7287010754462;524.7218973943816;533.1735809495267;4181;4292.6995110342605;4353.934690979638;4422.314541246112;4589.701669391479;12765.29790861493;12996.285204313665;13076.803847942288;13110;13128.081034327697;13023.218606883618;13038.348082595832;13038.674033149247;13045.739085218242;13143.737125257441;9005.63977440895;9318.72043838686;9356.532173391302;9587.424754514752;9587.472689002032;6429.646369449725;6454.419102280817;6569.9014514783275;6651.63416012124;6793.388595026471;11440.084793216689;11683.824742628698;11712.824307635563;11717.296962182296;11871.940640296743;11912.344821034927;11951.163418560618;12134.393280336124;12158.635240942785;12181.208221465577;8916.955415222883;9016.233620142362;9114.772130696785;9221.446713196961;9252.676156334495;9701.466419347003;9782.41305521671;9784.608615655294;9785.315027947976;9786.363886347364;8450.788730281547;8520.70177543783;8598.269147122315;8760.561971901507;8890.155435233642;8493.23560879499;8514.446560973516;8660.653573856975;8703.608337624948;8905.421147625384;8162.183781622028;8167.264946154874;8197.631106600098;8219.95890020546;8224.054233763194;5610;5635.971820140873;5668.886622267614;5706.4864162225595;5718.857028574319;5585.273914391103;5685.317761868602;5732.856678582902;5777.653340799566;5816 16:08:12 INFO - PROCESS | 21020 | |4;prototype.html;343.5379414687245;352.3041992065669;355.2681476159112;359.229452823693;361.66365280289335;5531.5298199653025;5596.356419011815;5617.129344951539;5636.689982050985;5646.475451628058;5534.118399744086;5549.833504994856;5569.164625306206;5619.129034999572;5626.493615574597;3882.9223415531674;4117.217728631558;4323.805428755713;4341.891452713676;4350.847720329789;4324.935125973113;4332.675049371294;4333.739975601474;4337.587929146742;4351.26028575143;4322.913541729164;4325.329573916048;4329.783510824462;4332.935005974913;4334.4179374528385;4179.2895207814745;4272.060146767722;4336.200476802123;4346.326319420463;4352.8476503322545;3838.136419305662;3852.7303088783797;3857.1707082977296;3858.7298889077774;3887.436321733361;5284.762185701614;5348.572114230852;5418.458154184589;5433.157860531584;5444.6188766786345;4805.303231031516;4962.429320628138;4979.456368525769;4982.476839931791;4997.575206107485;5741.712914354307;5745.425457454263;5746.339170995306;5752.424757524256;5758.3665796762325;4206.118808616077;4227.3447615619325;4229.70392072555;4232.76719780412;4256.9574304257185;5683.232763576905;5776.335721392052;5815.592908496449;5941.168236446901;5974.3726908674835;4337.804798784062;4352.695311328177;4366.912661746747;4396.142752163298;4402.471703395549;4437.489688685778;4438.889027774331;4446.377507149002;4447.177272204653;4488.887777805581;4928.852134435972;5051.747412629354;5055.545000949932;5057.7724002419245;5077.390713114376;5809.593328467051;5818.381728222317;5829.679367634821;5849.356570777211;5869.354371019184;1489.2032762472024;1518.1498360917922;1522.7944227529363;1533.4249656378859;1539.3150048228654;951.372094417681;986.9605215791431;998.3859926145514;999.7250756042147;1017.801528701896;4973.825916092919;4990.955517134545;5009.7495125243595;5021.342956824239;5024.547790698854;5248.868778280574;5256.316678831728;5277.393099793496;5277.419483856773;5281.412108067019;4858.736728450557;5000.9498005418545;5028.421731500925;5041.81517343424;5044.620104529974;2786.7566608880047;2792.3298408382248;2796.314902848815;2797.5523916173315;2808.118560564427;5389.973050134724;5398.542393553718;5403.972980135074;5454.61817672767;5457.516829842412;5301.5493683037375;5710;6120.387961203933;6131.50947924165;6159.384061593805;6151.631621825719;6203.503719702413;6219.304425158976;6220.253569571681;6236.656983865841;5773.884522309614;5992.700364981646;5997.4002599741425;6023.126646636126;6034.974054410673;5949.929012777741;5951.196588460502;5961.880762384815;5962.612430192024;5971.522278217819;5906.557008224487;5938.109283607493;6127.632342059491;6181;6189.164462797645;4291.141771645745;4321.2005778931;4345.304751239786;4352.238358287351;4384.605385515319;5837.416258374129;6019.247594050744;6031.517478601789;6099.817005489843;6138.263408391022;5790.85522861915;5796.362400135898;5824.271966004249;5824.3593204748295;5863.651360187096;5830.2420685310635;5838.970805145947;5854.736536855767;5884.9705751470965;5887.409539802473;5539.362973258127;5780.624259423124;5814.186013958134;5855.092460668603;5862.472377486047;5885.588008839341;5902.911456328073;5907.135039505751;5910.960718552093;5920.348761636131;5567.777288908398;5757.762081152572;5791.8552036199435;5796.362400135898;5796.82609521715 16:08:12 INFO - PROCESS | 21020 | |5;yui.html;835.4277320035776;843.207385058045;888.968886088987;935.9157675809175;969.3198306682024;16284.83715162848;17816.910915445405;17892.37376691816;17923.910380448095;17949.7307540387;16369.918150409261;16528.75206871898;16566.50300490987;16568.088755118493;16576.66846663066;751.8796992481205;871.6774793326447;875.4440930009426;897.5108565831605;908.600215905003;893.678275820704;895.0467751844297;895.1406649616376;897.8339007283673;898.3262553085186;941.6515889121035;945.7021038372891;946.3044662173331;946.4415994563207;946.6733976778035;792.7661339904741;911.8905731312252;939.9671011514632;941.2799208605422;943.0239701908491;691.1567887177636;692.4322055914153;692.7021380806251;692.972281108755;694.146200173784;16190.433334833342;16236.350545978146;16244.512664619962;16271.430499932445;16281.83718162815;15089.32098055579;15300.464483743126;15371.154586497738;15525.301361438762;15705.528834134995;14742.852571474255;14847.925760371238;14864.925675371662;14992.700145997129;16090.919545402316;4848.709077455329;4865.9026819463415;4876.073546026244;4928.211486162197;5117.82087626935;91073.54463227707;92377.53811230967;93198.5340073302;93311.53344233304;95315.52342238314;12408.875911240772;12503.437345319482;12524.436400361823;12796.680082998;12803.743925121445;21490.78509214888;21548.78451215499;23292.184773532845;23879;23918.162864299666;8870.15733505316;9144.771380715536;9192.63229470827;9193.540322983958;9223.907760922304;5069.949300507022;5198.428172900978;5266.104762190437;5310.495502927216;5423.647462914898;3739.9626003739613;3856.787876666754;3859.3053250415196;3862.3240932837202;3866.033491627093;675.1358261425383;679.4292794053019;681.2948598200937;692.8614277144591;703.5286358140058;735.981600459982;741.2032065529577;744.7281742164071;753.8944547763317;769.5228958046047;9761.609535618638;9771.609135634637;10100.090991810772;10107.29248952581;10123.341982771244;694.3195668245148;746.488655271141;879.1735768377705;880.9251213646784;886.5655828643918;5184.170532714747;5238.240455133984;5502.669839809544;5528.944710552923;5657.632253903483;104740;120114;120828.39585802014;122087.38956305162;123869.38065309616;62316.688416558536;63815.36184638187;64625.67687161534;94066.15654866461;102291;75122;79693.60153199197;82249;89299.10700892907;90626.54686726656;10246.38521688701;10532.57869685204;10808.621698240524;11103.403854729291;11294.60468883585;54501;54992.72503637456;55814;56277;56279.43720562906;72167.63916180596;78228.21771782209;79580.60209699145;81975.67158134702;83497;14238.430462781373;14418.350628072258;14525.273736313353;14651.267436628339;14890.553283401516;16131;16622;16770.664586707953;16992.235349409555;17902.731459027866;15183.924080379527;15255.542333730005;16403.425880094615;16553.172341383124;16967.745483818;14503;14591.854081459049;14609.123452592878;15066.397344106113;15572.84427155714;15286.77069843931;15293.923530382277;15704.293306800993;16271;16756.413525526546;12315;12422.751544968869;12462.252264864137;12598.685032873886;13541.932290338485;12878.549250776177;13085.756853098941;13164.407601657756;13327.733445331232;13495.460181592627 16:08:12 INFO - PROCESS | 21020 | __end_tp_report 16:08:12 INFO - PROCESS | 21020 | __start_cc_report 16:08:12 INFO - PROCESS | 21020 | _x_x_mozilla_cycle_collect,1003 16:08:12 INFO - PROCESS | 21020 | __end_cc_report 16:08:12 INFO - PROCESS | 21020 | __startTimestamp1471907292207__endTimestamp 16:08:12 INFO - PROCESS | 21020 | 16:08:12 INFO - PROCESS | 21020 | ------- Summary: start ------- 16:08:12 INFO - PROCESS | 21020 | Number of tests: 6 16:08:12 INFO - PROCESS | 21020 | 16:08:12 INFO - PROCESS | 21020 | [#0] dojo.html Cycles:165 Average:5665.05 Median:5872.30 stddev:659.06 (11.2%) stddev-sans-first:632.61 16:08:12 INFO - PROCESS | 21020 | Values: 3222.5 3256.5 3262.3 3281.1 3303.3 5635.2 5744.3 5757.6 5781.6 5783.9 5598.4 5651.6 5654.0 5655.4 5660.1 5044.4 5087.0 5106.6 5119.0 5155.6 5153.6 5200.1 5204.8 5205.0 5212.4 5060.4 5080.9 5091.4 5121.8 5135.9 5164.1 5177.9 5185.5 5188.4 5207.5 5027.4 5099.9 5100.5 5101.6 5151.8 5578.9 5666.7 5669.1 5674.4 5706.3 6168.3 6181.7 6184.0 6184.4 6185.6 5984.3 6075.6 6117.9 6137.3 6215.3 5740.7 5783.0 5797.2 5805.6 5810.4 6305.2 6317.5 6321.1 6322.4 6323.1 5878.9 5975.1 6009.1 6037.9 6109.4 6130.4 6165.0 6165.9 6166.1 6170.3 6006.2 6061.8 6062.9 6068.0 6084.1 5864.6 6020.0 6076.1 6087.0 6142.2 4644.1 4672.2 4681.1 4685.0 4710.5 4153.3 4171.1 4172.5 4187.6 4197.7 5659.9 5751.2 5841.4 5849.4 5917.5 5980.1 5991.8 5998.0 5999.7 6026.5 5643.2 5664.0 5674.7 5677.8 5682.6 5106.6 5260.0 5296.1 5306.5 5373.7 6387.2 6415.3 6417.4 6418.6 6427.6 6163.7 6177.4 6228.8 6233.3 6242.8 6057.1 6234.4 6286.9 6299.5 6359.6 6059.8 6064.8 6066.0 6067.4 6088.9 6152.3 6154.8 6159.2 6160.2 6161.7 5706.3 5858.3 5884.9 5901.4 5973.0 5889.1 5918.6 5925.7 5933.6 5939.3 5796.4 5849.8 5858.1 5862.0 5864.1 5872.3 6005.7 6086.0 6108.0 6115.7 5944.3 5944.7 5945.7 5948.4 5973.3 16:08:12 INFO - PROCESS | 21020 | 16:08:12 INFO - PROCESS | 21020 | [#1] ext.html Cycles:180 Average:67243.09 Median:40480.90 stddev:137428.12 (339.5%) stddev-sans-first:137722.58 16:08:12 INFO - PROCESS | 21020 | Values: 607.8 638.6 665.5 666.4 669.9 34829.7 37582.2 37916.1 49683.8 50653.7 48835.3 49093.0 49259.5 49266.5 49334.8 9292.3 9434.2 9441.0 9448.1 9451.4 8541.7 10815.1 11055.9 11080.8 11546.7 11447.8 11543.1 11551.4 11553.3 11561.4 8600.8 11264.9 11301.2 11338.9 11343.9 10832.1 11162.0 11181.2 11551.6 11568.7 45777.0 46151.5 46224.8 46231.3 46288.5 3853.7 4000.0 4107.0 4296.8 4589.9 34666.3 34833.1 34955.1 35264.6 35870.5 4741.6 4741.7 4786.9 4826.0 4864.9 396888.0 399761.0 400225.0 401258.0 401992.0 63485.4 63809.7 63810.7 63835.7 63926.0 39734.6 40644.0 40679.6 42643.4 42722.0 16930.9 17089.8 17102.4 17114.9 17138.2 12665.6 13014.5 13392.2 13413.1 13525.0 20456.7 21068.5 21088.3 21842.5 21885.8 7416.4 7474.1 7508.0 7609.4 7644.2 1068.8 1092.5 1150.5 1153.4 1190.4 35141.4 35584.1 36086.1 36287.5 38846.4 1198.7 1201.6 1205.6 1227.7 1230.5 17822.1 18149.8 18216.2 18226.6 18338.7 626748.0 639459.0 640316.0 640837.0 641381.0 61207.0 61639.0 61745.4 61931.7 61941.7 1369.0 1451.7 1464.3 1469.7 1506.7 33888.3 35767.0 35777.8 35838.8 35843.3 52981.2 53480.0 53596.7 53644.2 53705.5 482366.0 482611.0 486274.0 486302.0 487645.0 30183.8 30960.5 31749.4 31767.2 32896.0 51741.7 51985.0 52349.0 52453.7 53394.7 43083.4 43252.8 43531.8 43834.6 44342.8 43217.2 44644.8 44692.3 44892.6 45365.0 43719.0 45277.5 46772.0 46873.1 48111.8 37729.0 40317.8 40733.8 40887.8 41708.8 29103.4 30253.4 30497.8 31152.0 32195.0 16:08:12 INFO - PROCESS | 21020 | 16:08:12 INFO - PROCESS | 21020 | [#2] jquery.html Cycles:180 Average:28823.43 Median:21339.00 stddev:75460.80 (353.6%) stddev-sans-first:75642.31 16:08:12 INFO - PROCESS | 21020 | Values: 402.7 412.9 422.7 429.3 437.9 15936.9 20201.4 21781.0 21832.8 22086.1 20266.8 20838.6 20932.6 20971.4 21040.2 5828.6 5848.0 6045.1 6047.4 6073.3 5906.1 5988.6 6003.4 6043.7 6046.0 5762.9 5835.6 5875.0 6005.8 6025.7 5827.3 5845.9 5866.3 5872.5 5906.6 5296.8 5405.7 5518.0 5550.1 5619.4 14519.1 14762.1 16503.0 23938.8 24196.6 2452.9 2482.2 2497.2 2683.5 2720.1 10255.3 12196.7 15330.4 15569.7 15904.9 1990.3 1991.3 2003.8 2025.9 2035.6 87877.6 88010.0 88055.6 88523.6 90552.0 10555.8 10594.2 11949.3 12024.7 12118.4 16398.7 16728.2 16797.2 16808.4 16820.1 8843.6 8870.9 8910.1 8920.0 8937.6 8587.7 8618.3 8622.7 8813.7 8846.1 4853.0 4879.2 5142.5 5181.6 5193.1 2086.2 2101.8 2111.8 2145.1 2174.3 1055.9 1059.4 1088.7 1110.4 1165.7 20179.2 21169.5 21336.0 21342.0 21348.8 1025.7 1074.7 1095.1 1103.1 1105.5 11308.2 11803.5 11865.6 11911.5 11987.7 382494.0 383428.0 387588.0 534902.0 543997.0 101407.5 105320.0 107620.0 108120.0 109219.5 1051.5 1070.2 1098.1 1104.7 1108.0 14381.6 17871.3 18037.9 19115.5 19200.6 33165.3 33372.0 45168.8 45170.3 45824.8 55655.7 61410.0 62334.0 62652.1 64693.0 8492.8 8858.7 8908.2 8908.8 9306.2 10559.7 12849.2 13059.9 13295.7 13328.5 11545.7 11801.4 11874.1 12111.7 12208.0 11742.0 11918.4 12512.4 12855.6 12864.6 11890.7 12623.7 12813.6 13298.3 13446.3 7886.7 8480.0 9129.3 9148.1 9453.0 11385.3 12148.9 12216.9 12356.1 12472.4 16:08:12 INFO - PROCESS | 21020 | 16:08:12 INFO - PROCESS | 21020 | [#3] mootools.html Cycles:180 Average:6622.42 Median:4323.32 stddev:3925.86 (90.8%) stddev-sans-first:3918.33 16:08:12 INFO - PROCESS | 21020 | Values: 1544.6 1609.4 1821.2 1900.2 1958.3 9471.3 10254.0 10261.6 10263.7 10267.1 8765.6 9176.0 9336.9 9394.8 10023.6 2553.9 2587.3 2590.2 2597.0 2600.5 2493.5 2526.4 2547.0 2552.2 2553.6 2553.4 2559.1 2585.2 2587.2 2602.9 2496.1 2517.5 2526.0 2530.0 2530.3 2498.5 2498.5 2503.4 2508.3 2534.8 10066.2 10266.6 10269.6 10276.0 10283.4 5903.5 6137.4 6137.5 6146.4 6148.9 3751.3 3835.2 3916.0 3921.5 3930.7 1264.1 1304.5 1319.5 1327.2 1342.4 14558.4 14623.1 14630.4 14639.0 14671.3 5119.4 5253.4 5386.0 5408.2 5417.2 12031.2 12046.6 12046.8 12047.8 12060.2 5960.3 5963.3 5965.0 5969.1 5969.7 4859.1 5095.9 5175.9 5229.8 5441.2 4468.0 4511.5 4521.8 4524.5 4619.2 1448.4 1457.1 1473.6 1479.4 1480.2 957.2 1026.6 1050.3 1051.2 1062.9 6181.3 6347.3 6405.2 6463.7 6597.9 518.2 519.7 521.7 524.7 533.2 4181.0 4292.7 4353.9 4422.3 4589.7 12765.3 12996.3 13076.8 13110.0 13128.1 13023.2 13038.3 13038.7 13045.7 13143.7 9005.6 9318.7 9356.5 9587.4 9587.5 6429.6 6454.4 6569.9 6651.6 6793.4 11440.1 11683.8 11712.8 11717.3 11871.9 11912.3 11951.2 12134.4 12158.6 12181.2 8917.0 9016.2 9114.8 9221.4 9252.7 9701.5 9782.4 9784.6 9785.3 9786.4 8450.8 8520.7 8598.3 8760.6 8890.2 8493.2 8514.4 8660.7 8703.6 8905.4 8162.2 8167.3 8197.6 8220.0 8224.1 5610.0 5636.0 5668.9 5706.5 5718.9 5585.3 5685.3 5732.9 5777.7 5816.0 16:08:12 INFO - PROCESS | 21020 | 16:08:12 INFO - PROCESS | 21020 | [#4] prototype.html Cycles:180 Average:4831.83 Median:5401.26 stddev:1414.35 (26.2%) stddev-sans-first:1377.61 16:08:12 INFO - PROCESS | 21020 | Values: 343.5 352.3 355.3 359.2 361.7 5531.5 5596.4 5617.1 5636.7 5646.5 5534.1 5549.8 5569.2 5619.1 5626.5 3882.9 4117.2 4323.8 4341.9 4350.8 4324.9 4332.7 4333.7 4337.6 4351.3 4322.9 4325.3 4329.8 4332.9 4334.4 4179.3 4272.1 4336.2 4346.3 4352.8 3838.1 3852.7 3857.2 3858.7 3887.4 5284.8 5348.6 5418.5 5433.2 5444.6 4805.3 4962.4 4979.5 4982.5 4997.6 5741.7 5745.4 5746.3 5752.4 5758.4 4206.1 4227.3 4229.7 4232.8 4257.0 5683.2 5776.3 5815.6 5941.2 5974.4 4337.8 4352.7 4366.9 4396.1 4402.5 4437.5 4438.9 4446.4 4447.2 4488.9 4928.9 5051.7 5055.5 5057.8 5077.4 5809.6 5818.4 5829.7 5849.4 5869.4 1489.2 1518.1 1522.8 1533.4 1539.3 951.4 987.0 998.4 999.7 1017.8 4973.8 4991.0 5009.7 5021.3 5024.5 5248.9 5256.3 5277.4 5277.4 5281.4 4858.7 5000.9 5028.4 5041.8 5044.6 2786.8 2792.3 2796.3 2797.6 2808.1 5390.0 5398.5 5404.0 5454.6 5457.5 5301.5 5710.0 6120.4 6131.5 6159.4 6151.6 6203.5 6219.3 6220.3 6236.7 5773.9 5992.7 5997.4 6023.1 6035.0 5949.9 5951.2 5961.9 5962.6 5971.5 5906.6 5938.1 6127.6 6181.0 6189.2 4291.1 4321.2 4345.3 4352.2 4384.6 5837.4 6019.2 6031.5 6099.8 6138.3 5790.9 5796.4 5824.3 5824.4 5863.7 5830.2 5839.0 5854.7 5885.0 5887.4 5539.4 5780.6 5814.2 5855.1 5862.5 5885.6 5902.9 5907.1 5911.0 5920.3 5567.8 5757.8 5791.9 5796.4 5796.8 16:08:12 INFO - PROCESS | 21020 | 16:08:12 INFO - PROCESS | 21020 | [#5] yui.html Cycles:180 Average:21778.37 Median:4870.99 stddev:30005.51 (616.0%) stddev-sans-first:30048.47 16:08:12 INFO - PROCESS | 21020 | Values: 835.4 843.2 889.0 935.9 969.3 16284.8 17816.9 17892.4 17923.9 17949.7 16369.9 16528.8 16566.5 16568.1 16576.7 751.9 871.7 875.4 897.5 908.6 893.7 895.0 895.1 897.8 898.3 941.7 945.7 946.3 946.4 946.7 792.8 911.9 940.0 941.3 943.0 691.2 692.4 692.7 693.0 694.1 16190.4 16236.4 16244.5 16271.4 16281.8 15089.3 15300.5 15371.2 15525.3 15705.5 14742.9 14847.9 14864.9 14992.7 16090.9 4848.7 4865.9 4876.1 4928.2 5117.8 91073.5 92377.5 93198.5 93311.5 95315.5 12408.9 12503.4 12524.4 12796.7 12803.7 21490.8 21548.8 23292.2 23879.0 23918.2 8870.2 9144.8 9192.6 9193.5 9223.9 5069.9 5198.4 5266.1 5310.5 5423.6 3740.0 3856.8 3859.3 3862.3 3866.0 675.1 679.4 681.3 692.9 703.5 736.0 741.2 744.7 753.9 769.5 9761.6 9771.6 10100.1 10107.3 10123.3 694.3 746.5 879.2 880.9 886.6 5184.2 5238.2 5502.7 5528.9 5657.6 104740.0 120114.0 120828.4 122087.4 123869.4 62316.7 63815.4 64625.7 94066.2 102291.0 75122.0 79693.6 82249.0 89299.1 90626.5 10246.4 10532.6 10808.6 11103.4 11294.6 54501.0 54992.7 55814.0 56277.0 56279.4 72167.6 78228.2 79580.6 81975.7 83497.0 14238.4 14418.4 14525.3 14651.3 14890.6 16131.0 16622.0 16770.7 16992.2 17902.7 15183.9 15255.5 16403.4 16553.2 16967.7 14503.0 14591.9 14609.1 15066.4 15572.8 15286.8 15293.9 15704.3 16271.0 16756.4 12315.0 12422.8 12462.3 12598.7 13541.9 12878.5 13085.8 13164.4 13327.7 13495.5 16:08:12 INFO - PROCESS | 21020 | -------- Summary: end -------- 16:08:12 INFO - PROCESS | 21020 | 16:08:12 INFO - TEST-INFO | 21020: exit 0 16:08:13 INFO - TEST-OK | dromaeo_css | took 1113870ms 16:08:13 INFO - TEST-START | kraken 16:08:13 INFO - operating with platform_type : linux_ 16:08:13 INFO - Initialising browser for kraken test... 16:08:13 INFO - TEST-INFO | started process 21221 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7KYAen/profile http://localhost:34016/getInfo.html) 16:08:16 INFO - PROCESS | 21221 | __metrics Screen width/height:1600/1200 16:08:16 INFO - PROCESS | 21221 | colorDepth:24 16:08:16 INFO - PROCESS | 21221 | Browser inner width/height: 1024/697 16:08:16 INFO - PROCESS | 21221 | __metrics 16:08:17 INFO - PROCESS | 21221 | 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" 16:08:17 INFO - PROCESS | 21221 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:08:18 INFO - TEST-INFO | 21221: exit 0 16:08:18 INFO - Browser initialized. 16:08:18 INFO - Running cycle 1/1 for kraken test... 16:08:18 INFO - TEST-INFO | started process 21310 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7KYAen/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:30 INFO - PROCESS | 21310 | RSS: Main: 144982016 16:08:30 INFO - PROCESS | 21310 | 16:08:33 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34016/tests/kraken/driver.html?testName=audio-beat-detection) 16:08:33 INFO - PROCESS | 21310 | RSS: Main: 191287296 16:08:33 INFO - PROCESS | 21310 | 16:08:36 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34016/tests/kraken/driver.html?testName=audio-dft) 16:08:37 INFO - PROCESS | 21310 | RSS: Main: 181919744 16:08:37 INFO - PROCESS | 21310 | 16:08:39 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34016/tests/kraken/driver.html?testName=audio-fft) 16:08:40 INFO - PROCESS | 21310 | RSS: Main: 270557184 16:08:40 INFO - PROCESS | 21310 | 16:08:42 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34016/tests/kraken/driver.html?testName=audio-oscillator) 16:08:43 INFO - PROCESS | 21310 | RSS: Main: 175992832 16:08:43 INFO - PROCESS | 21310 | 16:08:45 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34016/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:08:45 INFO - PROCESS | 21310 | RSS: Main: 177139712 16:08:45 INFO - PROCESS | 21310 | 16:08:48 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34016/tests/kraken/driver.html?testName=imaging-darkroom) 16:08:49 INFO - PROCESS | 21310 | RSS: Main: 213315584 16:08:49 INFO - PROCESS | 21310 | 16:08:52 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34016/tests/kraken/driver.html?testName=imaging-desaturate) 16:08:53 INFO - PROCESS | 21310 | RSS: Main: 211918848 16:08:53 INFO - PROCESS | 21310 | 16:08:56 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34016/tests/kraken/driver.html?testName=json-parse-financial) 16:08:56 INFO - PROCESS | 21310 | RSS: Main: 211419136 16:08:56 INFO - PROCESS | 21310 | 16:08:58 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34016/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:08:58 INFO - PROCESS | 21310 | RSS: Main: 176218112 16:08:58 INFO - PROCESS | 21310 | 16:09:00 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34016/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:09:01 INFO - PROCESS | 21310 | RSS: Main: 181280768 16:09:01 INFO - PROCESS | 21310 | 16:09:03 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34016/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:09:03 INFO - PROCESS | 21310 | RSS: Main: 185741312 16:09:03 INFO - PROCESS | 21310 | 16:09:06 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34016/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:09:06 INFO - PROCESS | 21310 | RSS: Main: 180838400 16:09:06 INFO - PROCESS | 21310 | 16:09:09 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34016/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:09:09 INFO - PROCESS | 21310 | RSS: Main: 184758272 16:09:09 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | Cycle 1(1): loaded http://localhost:34016/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34016/tests/kraken/driver.html?testName=ai-astar) 16:09:11 INFO - PROCESS | 21310 | RSS: Main: 235499520 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | __start_tp_report 16:09:11 INFO - PROCESS | 21310 | _x_x_mozilla_page_load 16:09:11 INFO - PROCESS | 21310 | _x_x_mozilla_page_load_details 16:09:11 INFO - PROCESS | 21310 | |i|pagename|runs| 16:09:11 INFO - PROCESS | 21310 | |0;ai-astar;103;111;110;109;111;110;109;109;110;111 16:09:11 INFO - PROCESS | 21310 | |1;audio-beat-detection;149;116;119;181;115;120;155;113;148;114 16:09:11 INFO - PROCESS | 21310 | |2;audio-dft;159;159;159;160;160;158;161;160;160;160 16:09:11 INFO - PROCESS | 21310 | |3;audio-fft;75;75;75;100;75;75;74;75;97;74 16:09:11 INFO - PROCESS | 21310 | |4;audio-oscillator;83;82;122;83;83;83;115;83;84;126 16:09:11 INFO - PROCESS | 21310 | |5;imaging-gaussian-blur;116;116;117;117;117;116;117;117;116;117 16:09:11 INFO - PROCESS | 21310 | |6;imaging-darkroom;145;146;146;146;146;146;146;146;146;146 16:09:11 INFO - PROCESS | 21310 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;95;95 16:09:11 INFO - PROCESS | 21310 | |8;json-parse-financial;56;55;52;51;52;53;53;52;51;52 16:09:11 INFO - PROCESS | 21310 | |9;json-stringify-tinderbox;60;63;62;61;61;61;60;61;61;61 16:09:11 INFO - PROCESS | 21310 | |10;stanford-crypto-aes;73;72;72;72;81;75;82;78;72;72 16:09:11 INFO - PROCESS | 21310 | |11;stanford-crypto-ccm;129;115;119;125;114;123;116;120;158;120 16:09:11 INFO - PROCESS | 21310 | |12;stanford-crypto-pbkdf2;152;153;151;149;149;157;151;149;152;150 16:09:11 INFO - PROCESS | 21310 | |13;stanford-crypto-sha256-iterative;63;63;62;61;61;62;60;64;59;60 16:09:11 INFO - PROCESS | 21310 | __end_tp_report 16:09:11 INFO - PROCESS | 21310 | __start_cc_report 16:09:11 INFO - PROCESS | 21310 | _x_x_mozilla_cycle_collect,1600 16:09:11 INFO - PROCESS | 21310 | __end_cc_report 16:09:11 INFO - PROCESS | 21310 | __startTimestamp1471907351882__endTimestamp 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | ------- Summary: start ------- 16:09:11 INFO - PROCESS | 21310 | Number of tests: 14 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:2.36 (2.1%) stddev-sans-first:0.87 16:09:11 INFO - PROCESS | 21310 | Values: 103.0 111.0 110.0 109.0 111.0 110.0 109.0 109.0 110.0 111.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:134.00 stddev:23.59 (17.6%) stddev-sans-first:24.30 16:09:11 INFO - PROCESS | 21310 | Values: 149.0 116.0 119.0 181.0 115.0 120.0 155.0 113.0 148.0 114.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#2] audio-dft Cycles:10 Average:159.60 Median:160.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 16:09:11 INFO - PROCESS | 21310 | Values: 159.0 159.0 159.0 160.0 160.0 158.0 161.0 160.0 160.0 160.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#3] audio-fft Cycles:10 Average:79.50 Median:75.00 stddev:10.05 (13.4%) stddev-sans-first:10.52 16:09:11 INFO - PROCESS | 21310 | Values: 75.0 75.0 75.0 100.0 75.0 75.0 74.0 75.0 97.0 74.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:83.00 stddev:18.55 (22.3%) stddev-sans-first:19.21 16:09:11 INFO - PROCESS | 21310 | Values: 83.0 82.0 122.0 83.0 83.0 83.0 115.0 83.0 84.0 126.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:09:11 INFO - PROCESS | 21310 | Values: 116.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.00 16:09:11 INFO - PROCESS | 21310 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:09:11 INFO - PROCESS | 21310 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:52.50 stddev:1.64 (3.1%) stddev-sans-first:1.22 16:09:11 INFO - PROCESS | 21310 | Values: 56.0 55.0 52.0 51.0 52.0 53.0 53.0 52.0 51.0 52.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.88 (1.4%) stddev-sans-first:0.83 16:09:11 INFO - PROCESS | 21310 | Values: 60.0 63.0 62.0 61.0 61.0 61.0 60.0 61.0 61.0 61.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:74.00 stddev:3.98 (5.4%) stddev-sans-first:4.17 16:09:11 INFO - PROCESS | 21310 | Values: 73.0 72.0 72.0 72.0 81.0 75.0 82.0 78.0 72.0 72.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:121.50 stddev:12.84 (10.6%) stddev-sans-first:13.49 16:09:11 INFO - PROCESS | 21310 | Values: 129.0 115.0 119.0 125.0 114.0 123.0 116.0 120.0 158.0 120.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:151.50 stddev:2.45 (1.6%) stddev-sans-first:2.59 16:09:11 INFO - PROCESS | 21310 | Values: 152.0 153.0 151.0 149.0 149.0 157.0 151.0 149.0 152.0 150.0 16:09:11 INFO - PROCESS | 21310 | 16:09:11 INFO - PROCESS | 21310 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:1.58 (2.6%) stddev-sans-first:1.58 16:09:11 INFO - PROCESS | 21310 | Values: 63.0 63.0 62.0 61.0 61.0 62.0 60.0 64.0 59.0 60.0 16:09:11 INFO - PROCESS | 21310 | -------- Summary: end -------- 16:09:11 INFO - PROCESS | 21310 | 16:09:12 INFO - TEST-INFO | 21310: exit 0 16:09:12 INFO - TEST-OK | kraken | took 59924ms 16:09:12 INFO - SUITE-END | took 1173s 16:09:13 INFO - Completed test suite (00:19:33) 16:09:13 INFO - javascript benchmark 16:09:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3222.5327327537507, 3256.478963365862, 3262.265990152215, 3281.1469018055327, 3303.322818822142, 5635.211070450135, 5744.253247077884, 5757.625754325966, 5781.595288329819, 5783.884322313556, 5598.384177740445, 5651.5761317901215, 5653.971730141344, 5655.4061823508555, 5660.094384898417, 5044.369453818273, 5087.0, 5106.617003724717, 5119.0, 5155.58755299577, 5153.56194723449, 5200.141976573861, 5204.817831375903, 5205.0370681423865, 5212.400573934008, 5060.39275286966, 5080.872978175556, 5091.439941606421, 5121.795128194886, 5135.871603209912, 5164.147915593922, 5177.896442071156, 5185.507376799198, 5188.377394712658, 5207.505286997729, 5027.396712394536, 5099.898002039939, 5100.464451232641, 5101.591872650179, 5151.845444636667, 5578.944210557883, 5666.716664166775, 5669.121286200645, 5674.375818659944, 5706.28671416073, 6168.259808822925, 6181.690915454209, 6184.041473571604, 6184.381561843825, 6185.628862268279, 5984.311804142536, 6075.635461872302, 6117.918752924055, 6137.2635283765785, 6215.2541694996435, 5740.712964351766, 5783.0, 5797.188393624896, 5805.593608447452, 5810.448007439329, 6305.211848518935, 6317.494600432046, 6321.1150438938585, 6322.430981211713, 6323.146375239284, 5878.853028674317, 5975.073863551156, 6009.098635204667, 6037.879242415127, 6109.389061093855, 6130.356312587203, 6165.0444181152, 6165.876682466415, 6166.075088736726, 6170.290416602058, 6006.219191505076, 6061.787837425669, 6062.878742425127, 6067.969660151671, 6084.087386892002, 5864.589478736533, 6020.0, 6076.149339092531, 6087.026075827935, 6142.170806941095, 4644.140833945731, 4672.159011377985, 4681.133990211822, 4684.976575117103, 4710.52894710533, 4153.273177193979, 4171.0615111599645, 4172.520160181557, 4187.6440502557625, 4197.685173611931, 5659.915101273484, 5751.166080918243, 5841.4450627190345, 5849.356570777211, 5917.467427931507, 5980.073088671263, 5991.820245392645, 5997.970010149921, 5999.700014999233, 6026.487748541423, 5643.181738647955, 5664.0, 5674.659520428788, 5677.829665110054, 5682.602217844711, 5106.591472682177, 5259.973700131475, 5296.09966305729, 5306.495882891119, 5373.650712703662, 6387.169667943138, 6415.3263907289065, 6417.358264173638, 6418.646974416265, 6427.550071495044, 6163.722632481638, 6177.413145751147, 6228.781992630236, 6233.283172435046, 6242.750289988349, 6057.121717351048, 6234.438900498977, 6286.937130628635, 6299.527535434769, 6359.554831161774, 6059.848503787441, 6064.818055458167, 6065.968785306421, 6067.393260674074, 6088.908666370096, 6152.261728592597, 6154.753809847735, 6159.168512250789, 6160.199174107516, 6161.691915404302, 5706.3152421709665, 5858.326292476419, 5884.911291411103, 5901.439363260483, 5973.0, 5889.057750759858, 5918.585699001029, 5925.674087925033, 5933.614315069507, 5939.346671866005, 5796.420357964338, 5849.824505264849, 5858.121281807763, 5862.032764593794, 5864.06175011996, 5872.29532456108, 6005.669688167192, 6085.9695701521205, 6108.0, 6115.696722738907, 5944.256967879015, 5944.73248703818, 5945.732442040205, 5948.37542058095, 5973.253343332083], "value": 5619.515650701094, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [607.7568972411035, 638.5944924972641, 665.5208250059958, 666.3569655282652, 669.8660267946409, 34829.65170348296, 37582.24835503292, 37916.05209869751, 49683.75158124213, 50653.74673126629, 48835.26747098788, 49093.0, 49259.50740492594, 49266.50733492664, 49334.753326233316, 9292.349535532516, 9434.245260379188, 9440.952795236015, 9448.055194480532, 9451.432914025181, 8541.701040463586, 10815.134789216843, 11055.944720276428, 11080.778384432306, 11546.71133221672, 11447.771044579104, 11543.076553875711, 11551.364674942875, 11553.30680159193, 11561.364124973123, 8600.784980375478, 11264.943675281613, 11301.152413569016, 11338.943305283503, 11343.943280283545, 10832.133429325713, 11162.0, 11181.161412894066, 11551.595694150748, 11568.710782230512, 45777.0, 46151.5384846154, 46224.768876155744, 46231.30653040207, 46288.53711462876, 3853.7495062820826, 4000.0399904023116, 4107.014316564033, 4296.806643701041, 4589.931151032737, 34666.30667386663, 34833.129171770655, 34955.1261218469, 35264.647353526394, 35870.48821666557, 4741.620670346364, 4741.668083234175, 4786.880327991793, 4826.0, 4864.854054378375, 396888.0, 399761.0, 400225.0, 401257.9937100325, 401992.0, 63485.36514634795, 63809.68095159494, 63810.72378552403, 63835.6808215956, 63926.0, 39734.60265397367, 40644.0, 40679.59320406817, 42643.36034959478, 42722.0, 16930.915345423193, 17089.829101709074, 17102.40141595063, 17114.914425427793, 17138.228779704943, 12665.556705515262, 13014.544490942772, 13392.196468211743, 13413.128146670435, 13525.0, 20456.69314960277, 21068.47328816792, 21088.261910833047, 21842.453938651342, 21885.781142188374, 7416.3696085832225, 7474.1404738454685, 7508.0, 7609.429292803062, 7644.159142494321, 1068.8076146293586, 1092.4592326798127, 1150.5455345138623, 1153.382940127028, 1190.3691043746828, 35141.43759518648, 35584.11471595643, 36086.097847553494, 36287.4556881647, 38846.41730374047, 1198.7302856857136, 1201.567435723139, 1205.5659962413524, 1227.6746662134542, 1230.464747834677, 17822.10889455548, 18149.833302601834, 18216.180271887795, 18226.63546729058, 18338.724919126224, 626748.0, 639459.0, 640316.0, 640837.0, 641381.0, 61207.0, 61639.0, 61745.38254617396, 61931.690341548005, 61941.69029154915, 1369.0, 1451.7314296855116, 1464.282501574221, 1469.6987117640858, 1506.691128318692, 33888.322233555686, 35767.0, 35777.82111089428, 35838.820805896845, 35843.28313433665, 52981.205281921575, 53480.0, 53596.732016339665, 53644.19533706919, 53705.46294537005, 482366.0, 482611.0, 486274.0, 486302.0, 487645.0, 30183.849080754455, 30960.53559196659, 31749.36501270008, 31767.205819854687, 32896.0, 51741.7412912933, 51985.0, 52349.0, 52453.7377313111, 53394.73302633462, 43083.35374969315, 43252.783736082376, 43531.78234108809, 43834.56165438305, 44342.77828610966, 43217.24610292928, 44644.77677611591, 44692.32961505645, 44892.55107448884, 45365.0, 43719.0, 45277.547224527334, 46772.0, 46873.06253874971, 48111.75944120257, 37729.0, 40317.79841100776, 40733.79633101816, 40887.795561022, 41708.79145604253, 29103.41793164167, 30253.394932101673, 30497.847510763193, 31152.0, 32195.0], "value": 21968.6707685017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.7313961022776, 412.92360913231056, 422.6513126670496, 429.34310504927464, 437.89490522274644, 15936.920315398422, 20201.39395818126, 21781.0, 21832.781672183293, 22086.11655533777, 20266.797332026712, 20838.583228335425, 20932.581348373024, 20971.37085887422, 21040.158393664235, 5828.562857785661, 5848.0, 6045.093236014589, 6047.42549457803, 6073.271207455112, 5906.05503119501, 5988.640681559121, 6003.429674180946, 6043.6675982820925, 6045.972184728606, 5762.884742305151, 5835.5915085944, 5875.0, 6005.849853753647, 6025.728842202111, 5827.300723913115, 5845.941540584604, 5866.325372582122, 5872.530197584183, 5906.5570082244, 5296.814611488579, 5405.675659460406, 5518.034343989786, 5550.111982082888, 5619.409961953977, 14519.056261343085, 14762.114273143647, 16503.0, 23938.760612393828, 24196.637050444257, 2452.889619967106, 2482.1932871816553, 2497.225859983411, 2683.5169669459488, 2720.088770261945, 10255.282130250886, 12196.695082622917, 15330.386784528606, 15569.688606227812, 15904.920475397665, 1990.3033938121541, 1991.2731852873742, 2003.7896020917885, 2025.9392218233477, 2035.562354093862, 87877.56061219717, 88010.0, 88055.55972220162, 88523.55738221332, 90552.0, 10555.84166237507, 10594.205434592437, 11949.342786146672, 12024.699382515508, 12118.39408029595, 16398.67202655964, 16728.16359182036, 16797.160141992852, 16808.411705590246, 16820.0748958806, 8843.60203790831, 8870.86693699595, 8910.108989101167, 8920.0, 8937.553122343857, 8587.699430519902, 8618.267447266928, 8622.741317760478, 8813.691520796741, 8846.115388461103, 4853.0, 4879.219324908068, 5142.460041695643, 5181.637285390062, 5193.1171700810955, 2086.1759604955955, 2101.8003289687454, 2111.809937105668, 2145.0668959002883, 2174.2824867793906, 1055.9102476289602, 1059.4120263253815, 1088.6679562733284, 1110.3837370259585, 1165.7377090154816, 20179.192832286542, 21169.470763230733, 21336.0, 21342.0, 21348.78651213499, 1025.7281820317621, 1074.7313171707074, 1095.1074873977682, 1103.0679076180616, 1105.4528008635616, 11308.208425410143, 11803.468843902045, 11865.5847045353, 11911.523539058513, 11987.700307492209, 382494.0, 383428.0, 387588.0, 534902.0, 543997.0, 101407.49296253471, 105320.0, 107620.0, 108120.0, 109219.45390272998, 1051.51104736298, 1070.1866581398194, 1098.1215027977587, 1104.6520346091115, 1107.9723006924892, 14381.64045898861, 17871.28514859391, 18037.909810450863, 19115.47838349158, 19200.61598768045, 33165.33669326648, 33372.0, 45168.77415612901, 45170.322445164005, 45824.770876145405, 55655.721721391135, 61410.0, 62334.0, 62652.06021909584, 64693.0, 8492.830143397221, 8858.73423797287, 8908.198262156437, 8908.821823563621, 9306.208972237166, 10559.683209503728, 12849.164804287691, 13059.934700326437, 13295.734085318432, 13328.533501327407, 11545.711357216136, 11801.40992950332, 11874.109441791727, 12111.697207569881, 12208.023358131506, 11742.0, 11918.40407979615, 12512.43694033789, 12855.614331570068, 12864.614061578168, 11890.702732431413, 12623.684407889876, 12813.615591532269, 13298.335083245993, 13446.327683615977, 7886.72396466121, 8479.95760021196, 9129.269658427444, 9148.130927561871, 9453.0, 11385.316880987168, 12148.939255303667, 12216.877831221575, 12356.135070545335, 12472.376381180724], "value": 9711.106141770055, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.5906834688803, 1609.356257497001, 1821.1895706410644, 1900.1948149258208, 1958.265650381107, 9471.337006409545, 10254.02586754259, 10261.640842570512, 10263.743406414844, 10267.075963163314, 8765.561721913895, 9175.95412022939, 9336.906630933689, 9394.765130871732, 10023.649172278972, 2553.9233822985293, 2587.3014286142825, 2590.2358804152727, 2597.0390955346547, 2600.45390468001, 2493.463905260369, 2526.406294520795, 2546.987265063672, 2552.221572420411, 2553.5658937980493, 2553.438243586408, 2559.11710459892, 2585.1598230575046, 2587.2109006752935, 2602.908898188564, 2496.0889275414524, 2517.471331020479, 2525.9621105683427, 2530.02594001309, 2530.304166354249, 2498.487809998946, 2498.537770512461, 2503.3616427810794, 2508.2851387354517, 2534.835235709673, 10066.194704423628, 10266.640667576674, 10269.640562580274, 10276.023777741102, 10283.382997020202, 5903.49820265278, 6137.3862613738265, 6137.539684523634, 6146.416090471398, 6148.8770224595255, 3751.268502641986, 3835.1946091320856, 3916.0405700603533, 3921.5294164700135, 3930.7051971101996, 1264.0772236267576, 1304.4651692805903, 1319.5183757928382, 1327.2434712214042, 1342.449595665782, 14558.417663293456, 14623.122612643276, 14630.41478340865, 14639.0, 14671.26643667823, 5119.360079990001, 5253.4221235664045, 5386.030514507426, 5408.1887716842, 5417.18742188667, 12031.217970832044, 12046.638600841989, 12046.75906481883, 12047.75904481923, 12060.155789094679, 5960.344362120163, 5963.314218864799, 5965.0, 5969.074793407029, 5969.701514924236, 4859.1010663027455, 5095.8726031848755, 5175.948240517547, 5229.764660590283, 5441.211024401518, 4467.977660111744, 4511.548845115528, 4521.751303678329, 4524.502304746476, 4619.214733495314, 1448.3554818106052, 1457.0529156048653, 1473.572663927449, 1479.3934486860335, 1480.2006916265311, 957.2150836314295, 1026.5637104230673, 1050.3120456101267, 1051.1590727418188, 1062.8618279623754, 6181.289151747606, 6347.333529979378, 6405.167328247298, 6463.7091330890225, 6597.86804263912, 518.1631664861297, 519.7011718262014, 521.7287010754462, 524.7218973943816, 533.1735809495267, 4181.0, 4292.6995110342605, 4353.934690979638, 4422.314541246112, 4589.701669391479, 12765.29790861493, 12996.285204313665, 13076.803847942288, 13110.0, 13128.081034327697, 13023.218606883618, 13038.348082595832, 13038.674033149247, 13045.739085218242, 13143.737125257441, 9005.63977440895, 9318.72043838686, 9356.532173391302, 9587.424754514752, 9587.472689002032, 6429.646369449725, 6454.419102280817, 6569.9014514783275, 6651.63416012124, 6793.388595026471, 11440.084793216689, 11683.824742628698, 11712.824307635563, 11717.296962182296, 11871.940640296743, 11912.344821034927, 11951.163418560618, 12134.393280336124, 12158.635240942785, 12181.208221465577, 8916.955415222883, 9016.233620142362, 9114.772130696785, 9221.446713196961, 9252.676156334495, 9701.466419347003, 9782.41305521671, 9784.608615655294, 9785.315027947976, 9786.363886347364, 8450.788730281547, 8520.70177543783, 8598.269147122315, 8760.561971901507, 8890.155435233642, 8493.23560879499, 8514.446560973516, 8660.653573856975, 8703.608337624948, 8905.421147625384, 8162.183781622028, 8167.264946154874, 8197.631106600098, 8219.95890020546, 8224.054233763194, 5610.0, 5635.971820140873, 5668.886622267614, 5706.4864162225595, 5718.857028574319, 5585.273914391103, 5685.317761868602, 5732.856678582902, 5777.653340799566, 5816.0], "value": 5151.63274616888, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.5379414687245, 352.3041992065669, 355.2681476159112, 359.229452823693, 361.66365280289335, 5531.5298199653025, 5596.356419011815, 5617.129344951539, 5636.689982050985, 5646.475451628058, 5534.118399744086, 5549.833504994856, 5569.164625306206, 5619.129034999572, 5626.493615574597, 3882.9223415531674, 4117.217728631558, 4323.805428755713, 4341.891452713676, 4350.847720329789, 4324.935125973113, 4332.675049371294, 4333.739975601474, 4337.587929146742, 4351.26028575143, 4322.913541729164, 4325.329573916048, 4329.783510824462, 4332.935005974913, 4334.4179374528385, 4179.2895207814745, 4272.060146767722, 4336.200476802123, 4346.326319420463, 4352.8476503322545, 3838.136419305662, 3852.7303088783797, 3857.1707082977296, 3858.7298889077774, 3887.436321733361, 5284.762185701614, 5348.572114230852, 5418.458154184589, 5433.157860531584, 5444.6188766786345, 4805.303231031516, 4962.429320628138, 4979.456368525769, 4982.476839931791, 4997.575206107485, 5741.712914354307, 5745.425457454263, 5746.339170995306, 5752.424757524256, 5758.3665796762325, 4206.118808616077, 4227.3447615619325, 4229.70392072555, 4232.76719780412, 4256.9574304257185, 5683.232763576905, 5776.335721392052, 5815.592908496449, 5941.168236446901, 5974.3726908674835, 4337.804798784062, 4352.695311328177, 4366.912661746747, 4396.142752163298, 4402.471703395549, 4437.489688685778, 4438.889027774331, 4446.377507149002, 4447.177272204653, 4488.887777805581, 4928.852134435972, 5051.747412629354, 5055.545000949932, 5057.7724002419245, 5077.390713114376, 5809.593328467051, 5818.381728222317, 5829.679367634821, 5849.356570777211, 5869.354371019184, 1489.2032762472024, 1518.1498360917922, 1522.7944227529363, 1533.4249656378859, 1539.3150048228654, 951.372094417681, 986.9605215791431, 998.3859926145514, 999.7250756042147, 1017.801528701896, 4973.825916092919, 4990.955517134545, 5009.7495125243595, 5021.342956824239, 5024.547790698854, 5248.868778280574, 5256.316678831728, 5277.393099793496, 5277.419483856773, 5281.412108067019, 4858.736728450557, 5000.9498005418545, 5028.421731500925, 5041.81517343424, 5044.620104529974, 2786.7566608880047, 2792.3298408382248, 2796.314902848815, 2797.5523916173315, 2808.118560564427, 5389.973050134724, 5398.542393553718, 5403.972980135074, 5454.61817672767, 5457.516829842412, 5301.5493683037375, 5710.0, 6120.387961203933, 6131.50947924165, 6159.384061593805, 6151.631621825719, 6203.503719702413, 6219.304425158976, 6220.253569571681, 6236.656983865841, 5773.884522309614, 5992.700364981646, 5997.4002599741425, 6023.126646636126, 6034.974054410673, 5949.929012777741, 5951.196588460502, 5961.880762384815, 5962.612430192024, 5971.522278217819, 5906.557008224487, 5938.109283607493, 6127.632342059491, 6181.0, 6189.164462797645, 4291.141771645745, 4321.2005778931, 4345.304751239786, 4352.238358287351, 4384.605385515319, 5837.416258374129, 6019.247594050744, 6031.517478601789, 6099.817005489843, 6138.263408391022, 5790.85522861915, 5796.362400135898, 5824.271966004249, 5824.3593204748295, 5863.651360187096, 5830.2420685310635, 5838.970805145947, 5854.736536855767, 5884.9705751470965, 5887.409539802473, 5539.362973258127, 5780.624259423124, 5814.186013958134, 5855.092460668603, 5862.472377486047, 5885.588008839341, 5902.911456328073, 5907.135039505751, 5910.960718552093, 5920.348761636131, 5567.777288908398, 5757.762081152572, 5791.8552036199435, 5796.362400135898, 5796.82609521715], "value": 4385.804085161816, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [835.4277320035776, 843.207385058045, 888.968886088987, 935.9157675809175, 969.3198306682024, 16284.83715162848, 17816.910915445405, 17892.37376691816, 17923.910380448095, 17949.7307540387, 16369.918150409261, 16528.75206871898, 16566.50300490987, 16568.088755118493, 16576.66846663066, 751.8796992481205, 871.6774793326447, 875.4440930009426, 897.5108565831605, 908.600215905003, 893.678275820704, 895.0467751844297, 895.1406649616376, 897.8339007283673, 898.3262553085186, 941.6515889121035, 945.7021038372891, 946.3044662173331, 946.4415994563207, 946.6733976778035, 792.7661339904741, 911.8905731312252, 939.9671011514632, 941.2799208605422, 943.0239701908491, 691.1567887177636, 692.4322055914153, 692.7021380806251, 692.972281108755, 694.146200173784, 16190.433334833342, 16236.350545978146, 16244.512664619962, 16271.430499932445, 16281.83718162815, 15089.32098055579, 15300.464483743126, 15371.154586497738, 15525.301361438762, 15705.528834134995, 14742.852571474255, 14847.925760371238, 14864.925675371662, 14992.700145997129, 16090.919545402316, 4848.709077455329, 4865.9026819463415, 4876.073546026244, 4928.211486162197, 5117.82087626935, 91073.54463227707, 92377.53811230967, 93198.5340073302, 93311.53344233304, 95315.52342238314, 12408.875911240772, 12503.437345319482, 12524.436400361823, 12796.680082998, 12803.743925121445, 21490.78509214888, 21548.78451215499, 23292.184773532845, 23879.0, 23918.162864299666, 8870.15733505316, 9144.771380715536, 9192.63229470827, 9193.540322983958, 9223.907760922304, 5069.949300507022, 5198.428172900978, 5266.104762190437, 5310.495502927216, 5423.647462914898, 3739.9626003739613, 3856.787876666754, 3859.3053250415196, 3862.3240932837202, 3866.033491627093, 675.1358261425383, 679.4292794053019, 681.2948598200937, 692.8614277144591, 703.5286358140058, 735.981600459982, 741.2032065529577, 744.7281742164071, 753.8944547763317, 769.5228958046047, 9761.609535618638, 9771.609135634637, 10100.090991810772, 10107.29248952581, 10123.341982771244, 694.3195668245148, 746.488655271141, 879.1735768377705, 880.9251213646784, 886.5655828643918, 5184.170532714747, 5238.240455133984, 5502.669839809544, 5528.944710552923, 5657.632253903483, 104740.0, 120114.0, 120828.39585802014, 122087.38956305162, 123869.38065309616, 62316.688416558536, 63815.36184638187, 64625.67687161534, 94066.15654866461, 102291.0, 75122.0, 79693.60153199197, 82249.0, 89299.10700892907, 90626.54686726656, 10246.38521688701, 10532.57869685204, 10808.621698240524, 11103.403854729291, 11294.60468883585, 54501.0, 54992.72503637456, 55814.0, 56277.0, 56279.43720562906, 72167.63916180596, 78228.21771782209, 79580.60209699145, 81975.67158134702, 83497.0, 14238.430462781373, 14418.350628072258, 14525.273736313353, 14651.267436628339, 14890.553283401516, 16131.0, 16622.0, 16770.664586707953, 16992.235349409555, 17902.731459027866, 15183.924080379527, 15255.542333730005, 16403.425880094615, 16553.172341383124, 16967.745483818, 14503.0, 14591.854081459049, 14609.123452592878, 15066.397344106113, 15572.84427155714, 15286.77069843931, 15293.923530382277, 15704.293306800993, 16271.0, 16756.413525526546, 12315.0, 12422.751544968869, 12462.252264864137, 12598.685032873886, 13541.932290338485, 12878.549250776177, 13085.756853098941, 13164.407601657756, 13327.733445331232, 13495.460181592627], "value": 8333.197333539334, "unit": "score"}], "name": "dromaeo_css", "value": 7803.152063444875, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 110.0, 109.0, 111.0, 110.0, 109.0, 109.0, 110.0, 111.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 119.0, 181.0, 115.0, 120.0, 155.0, 113.0, 148.0, 114.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 159.0, 160.0, 160.0, 158.0, 161.0, 160.0, 160.0, 160.0], "value": 159.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 100.0, 75.0, 75.0, 74.0, 75.0, 97.0, 74.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 122.0, 83.0, 83.0, 83.0, 115.0, 83.0, 84.0, 126.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 52.0, 51.0, 52.0, 53.0, 53.0, 52.0, 51.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 62.0, 61.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 72.0, 81.0, 75.0, 82.0, 78.0, 72.0, 72.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 115.0, 119.0, 125.0, 114.0, 123.0, 116.0, 120.0, 158.0, 120.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 151.0, 149.0, 149.0, 157.0, 151.0, 149.0, 152.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 61.0, 61.0, 62.0, 60.0, 64.0, 59.0, 60.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1458.6000000000001, "alertThreshold": 2.0}]} 16:09:13 INFO - Return code: 0 16:09:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:09:13 INFO - # TBPL SUCCESS # 16:09:13 INFO - Running post-action listener: _resource_record_post_action 16:09:13 INFO - [mozharness: 2016-08-22 23:09:13.167974Z] Finished run-tests step (success) 16:09:13 INFO - Running post-run listener: _resource_record_post_run 16:09:13 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328589312; Read time: 12; Write time: 351888 16:09:13 INFO - TinderboxPrint: CPU usage
13.0% 16:09:13 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:09:13 INFO - TinderboxPrint: I/O write bytes / time
328,589,312 / 351,888 16:09:13 INFO - TinderboxPrint: CPU idle
8,273.4 (87.0%) 16:09:13 INFO - TinderboxPrint: CPU user
1,185.1 (12.5%) 16:09:13 INFO - TinderboxPrint: Swap in / out
0 / 0 16:09:13 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79159296; Read time: 0; Write time: 117020 16:09:13 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 249024512; Read time: 0; Write time: 234800 16:09:13 INFO - Running post-run listener: _upload_blobber_files 16:09:13 INFO - Blob upload gear active. 16:09:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:09:13 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 16:09:13 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:09:13 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:09:14 INFO - (blobuploader) - INFO - Open directory for files ... 16:09:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:09:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:09:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:17 INFO - (blobuploader) - INFO - Done attempting. 16:09:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:09:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:09:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:18 INFO - (blobuploader) - INFO - Done attempting. 16:09:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:09:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:09:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:20 INFO - (blobuploader) - INFO - Done attempting. 16:09:20 INFO - (blobuploader) - INFO - Iteration through files over. 16:09:20 INFO - Return code: 0 16:09:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:09:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:09:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1f0d3ed665202320e4bede344ef88847285102486a1043b883c1bf43732a8082fcf832ad87e7f1065da70601a3b25577dd9c34a9f2c88c168af3773e7879e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff2efd8cc7426f7073ab1e1e801b987c3e8595d2fbdb4075b4fd41ddf2e35ddc3b399b2aec2a345c0b1ef2c7c22e9a401ad8e36b1a1e4e46263d4d97147b2c7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d12570da484f1a9f88d3d7b79e5a25e330fb4ff4f1ce132ad5f595f3eb4329503a8b5a44fe240ecc2b5fb4e1da6e3c2ed5407d16a95c1007bf60aea51ed0e0e"} 16:09:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:09:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:09:20 INFO - Contents: 16:09:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1f0d3ed665202320e4bede344ef88847285102486a1043b883c1bf43732a8082fcf832ad87e7f1065da70601a3b25577dd9c34a9f2c88c168af3773e7879e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff2efd8cc7426f7073ab1e1e801b987c3e8595d2fbdb4075b4fd41ddf2e35ddc3b399b2aec2a345c0b1ef2c7c22e9a401ad8e36b1a1e4e46263d4d97147b2c7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d12570da484f1a9f88d3d7b79e5a25e330fb4ff4f1ce132ad5f595f3eb4329503a8b5a44fe240ecc2b5fb4e1da6e3c2ed5407d16a95c1007bf60aea51ed0e0e"} 16:09:20 INFO - Running post-run listener: copy_logs_to_upload_dir 16:09:20 INFO - Copying logs to upload dir... 16:09:20 INFO - mkdir: /builds/slave/test/build/upload/logs 16:09:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.647378 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-22 16:09:20.136928) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 16:09:20.140526) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942 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-Aurora/sha512/b1f0d3ed665202320e4bede344ef88847285102486a1043b883c1bf43732a8082fcf832ad87e7f1065da70601a3b25577dd9c34a9f2c88c168af3773e7879e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff2efd8cc7426f7073ab1e1e801b987c3e8595d2fbdb4075b4fd41ddf2e35ddc3b399b2aec2a345c0b1ef2c7c22e9a401ad8e36b1a1e4e46263d4d97147b2c7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d12570da484f1a9f88d3d7b79e5a25e330fb4ff4f1ce132ad5f595f3eb4329503a8b5a44fe240ecc2b5fb4e1da6e3c2ed5407d16a95c1007bf60aea51ed0e0e"} build_url:https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011370 build_url: 'https://queue.taskcluster.net/v1/task/GstekabnSUy9Dq-pz0cHjQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1f0d3ed665202320e4bede344ef88847285102486a1043b883c1bf43732a8082fcf832ad87e7f1065da70601a3b25577dd9c34a9f2c88c168af3773e7879e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff2efd8cc7426f7073ab1e1e801b987c3e8595d2fbdb4075b4fd41ddf2e35ddc3b399b2aec2a345c0b1ef2c7c22e9a401ad8e36b1a1e4e46263d4d97147b2c7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8d12570da484f1a9f88d3d7b79e5a25e330fb4ff4f1ce132ad5f595f3eb4329503a8b5a44fe240ecc2b5fb4e1da6e3c2ed5407d16a95c1007bf60aea51ed0e0e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 16:09:20.166497) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:09:20.166835) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1471905678.364911-1994132942 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004445 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:09:20.252389) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 16:09:20.252746) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 16:09:20.253107) ========= ========= Total master_lag: 0.16 =========