builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1473521829.51 results: success (0) buildid: 20160910055116 builduid: fb98bf9a800847589b1767e37e53bef3 revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:09.509814) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:09.510222) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:09.510493) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:09.601341) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:09.601605) ========= 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=fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-10 08:37:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.8M=0s 2016-09-10 08:37:11 (42.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078318 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:09.697303) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:09.697585) ========= 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=fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014146 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:09.746165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:09.746500) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8663d41e015f3c9933a08a65c8349b86f2263354 --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 8663d41e015f3c9933a08a65c8349b86f2263354 --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=fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-10 08:37:11,737 truncating revision to first 12 chars 2016-09-10 08:37:11,737 Setting DEBUG logging. 2016-09-10 08:37:11,737 attempt 1/10 2016-09-10 08:37:11,737 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8663d41e015f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-10 08:37:12,039 unpacking tar archive at: mozilla-aurora-8663d41e015f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.648055 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:10.407413) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:10.407692) ========= script_repo_revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:10.408055) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:10.408329) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 08:37:10.418443) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-10 08:37:10.418721) ========= /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=fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:37:12 INFO - MultiFileLogger online at 20160910 08:37:12 in /builds/slave/test 08:37:12 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 08:37:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:37:12 INFO - {'append_to_log': False, 08:37:12 INFO - 'base_work_dir': '/builds/slave/test', 08:37:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:37:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:37:12 INFO - 'branch': 'Mozilla-Aurora', 08:37:12 INFO - 'buildbot_json_path': 'buildprops.json', 08:37:12 INFO - 'config_files': ('talos/linux_config.py',), 08:37:12 INFO - 'default_actions': ('clobber', 08:37:12 INFO - 'read-buildbot-config', 08:37:12 INFO - 'download-and-extract', 08:37:12 INFO - 'populate-webroot', 08:37:12 INFO - 'create-virtualenv', 08:37:12 INFO - 'install', 08:37:12 INFO - 'run-tests'), 08:37:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:37:12 INFO - 'download_minidump_stackwalk': True, 08:37:12 INFO - 'download_symbols': 'ondemand', 08:37:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:37:12 INFO - 'tooltool.py': '/tools/tooltool.py', 08:37:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:37:12 INFO - '/tools/misc-python/virtualenv.py')}, 08:37:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:37:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:37:12 INFO - 'installer_path': 'installer.exe', 08:37:12 INFO - 'log_level': 'info', 08:37:12 INFO - 'log_name': 'talos', 08:37:12 INFO - 'log_to_console': True, 08:37:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:37:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:37:12 INFO - 'opt_config_files': (), 08:37:12 INFO - 'pip_index': False, 08:37:12 INFO - 'sps_profile': False, 08:37:12 INFO - 'sps_profile_interval': 0, 08:37:12 INFO - 'suite': 'dromaeojs', 08:37:12 INFO - 'system_bits': '32', 08:37:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:37:12 INFO - 'title': 'talos-linux64-ix-048', 08:37:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:37:12 INFO - 'use_talos_json': True, 08:37:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:37:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:37:12 INFO - 'work_dir': 'build'} 08:37:12 INFO - [mozharness: 2016-09-10 15:37:12.505103Z] Running clobber step. 08:37:12 INFO - Running pre-action listener: _resource_record_pre_action 08:37:12 INFO - Running main action method: clobber 08:37:12 INFO - rmtree: /builds/slave/test/build 08:37:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:37:12 INFO - Running post-action listener: _resource_record_post_action 08:37:12 INFO - [mozharness: 2016-09-10 15:37:12.966428Z] Finished clobber step (success) 08:37:12 INFO - [mozharness: 2016-09-10 15:37:12.966529Z] Running read-buildbot-config step. 08:37:12 INFO - Running pre-action listener: _resource_record_pre_action 08:37:12 INFO - Running main action method: read_buildbot_config 08:37:12 INFO - Using buildbot properties: 08:37:12 INFO - { 08:37:12 INFO - "project": "", 08:37:12 INFO - "product": "firefox", 08:37:12 INFO - "script_repo_revision": "production", 08:37:12 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:37:12 INFO - "repository": "", 08:37:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:37:12 INFO - "buildid": "20160910055116", 08:37:12 INFO - "pgo_build": "True", 08:37:12 INFO - "basedir": "/builds/slave/test", 08:37:12 INFO - "buildnumber": 744, 08:37:12 INFO - "slavename": "talos-linux64-ix-048", 08:37:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:37:12 INFO - "platform": "ubuntu64_hw", 08:37:12 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:37:12 INFO - "branch": "mozilla-aurora", 08:37:12 INFO - "repo_path": "releases/mozilla-aurora", 08:37:12 INFO - "revision": "8663d41e015f3c9933a08a65c8349b86f2263354", 08:37:12 INFO - "stage_platform": "linux64-pgo", 08:37:12 INFO - "builduid": "fb98bf9a800847589b1767e37e53bef3", 08:37:12 INFO - "slavebuilddir": "test" 08:37:12 INFO - } 08:37:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 08:37:12 INFO - Running post-action listener: _resource_record_post_action 08:37:12 INFO - [mozharness: 2016-09-10 15:37:12.968982Z] Finished read-buildbot-config step (success) 08:37:12 INFO - [mozharness: 2016-09-10 15:37:12.969072Z] Running download-and-extract step. 08:37:12 INFO - Running pre-action listener: _resource_record_pre_action 08:37:12 INFO - Running main action method: download_and_extract 08:37:12 INFO - mkdir: /builds/slave/test/build/tests 08:37:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:37:12 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:37:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:37:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:37:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:37:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:37:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:37:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:37:12 INFO - retry: Failed, sleeping 30 seconds before retrying 08:37:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:37:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:37:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:37:43 INFO - retry: Failed, sleeping 60 seconds before retrying 08:38:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:38:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:38:43 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:38:43 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 08:38:43 INFO - Caught exception: HTTP Error 404: Not Found 08:38:43 INFO - Caught exception: HTTP Error 404: Not Found 08:38:43 INFO - Caught exception: HTTP Error 404: Not Found 08:38:43 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:38:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:38:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:38:43 INFO - Downloaded 1361 bytes. 08:38:43 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 08:38:43 INFO - Using the following test package requirements: 08:38:43 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 08:38:43 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:38:43 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:38:43 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:43 INFO - u'jsshell-linux-x86_64.zip'], 08:38:43 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:38:43 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 08:38:43 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:38:43 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:38:43 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:38:43 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 08:38:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:38:43 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:38:43 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')]} 08:38:43 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:38:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:38:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:38:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:38:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:38:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:38:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:38:43 INFO - retry: Failed, sleeping 30 seconds before retrying 08:39:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:39:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:39:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:39:13 INFO - retry: Failed, sleeping 60 seconds before retrying 08:40:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:40:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:40:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:40:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 08:40:13 INFO - Caught exception: HTTP Error 404: Not Found 08:40:13 INFO - Caught exception: HTTP Error 404: Not Found 08:40:13 INFO - Caught exception: HTTP Error 404: Not Found 08:40:13 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:40:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:40:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:40:14 INFO - Downloaded 21770904 bytes. 08:40:14 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'] 08:40:14 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 08:40:15 INFO - Return code: 0 08:40:15 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 08:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:40:15 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:40:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:40:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:40:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:40:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:40:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:40:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:40:15 INFO - retry: Failed, sleeping 30 seconds before retrying 08:40:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:40:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:40:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:40:45 INFO - retry: Failed, sleeping 60 seconds before retrying 08:41:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:41:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:41:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:41:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 08:41:45 INFO - Caught exception: HTTP Error 404: Not Found 08:41:45 INFO - Caught exception: HTTP Error 404: Not Found 08:41:45 INFO - Caught exception: HTTP Error 404: Not Found 08:41:45 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 08:41:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:41:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:41:46 INFO - Downloaded 21770904 bytes. 08:41:46 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'] 08:41:46 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 08:41:48 INFO - Return code: 0 08:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:41:48 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:41:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:41:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:41:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:41:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:41:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:41:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:41:48 INFO - retry: Failed, sleeping 30 seconds before retrying 08:42:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:42:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:42:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:42:18 INFO - retry: Failed, sleeping 60 seconds before retrying 08:43:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:43:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:43:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:43:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 08:43:18 INFO - Caught exception: HTTP Error 404: Not Found 08:43:18 INFO - Caught exception: HTTP Error 404: Not Found 08:43:18 INFO - Caught exception: HTTP Error 404: Not Found 08:43:18 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 08:43:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:43:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:43:19 INFO - Downloaded 14413740 bytes. 08:43:19 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'] 08:43:19 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 08:43:20 INFO - Return code: 0 08:43:20 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')]} 08:43:20 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:43:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:43:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:43:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:43:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:43:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:43:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:43:20 INFO - retry: Failed, sleeping 30 seconds before retrying 08:43:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:43:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:43:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:43:50 INFO - retry: Failed, sleeping 60 seconds before retrying 08:44:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:44:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:44:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:44:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 08:44:50 INFO - Caught exception: HTTP Error 404: Not Found 08:44:50 INFO - Caught exception: HTTP Error 404: Not Found 08:44:50 INFO - Caught exception: HTTP Error 404: Not Found 08:44:50 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:44:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:44:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 08:44:51 INFO - Downloaded 63895992 bytes. 08:44:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:44:51 INFO - mkdir: /builds/slave/test/properties 08:44:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:51 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:51 INFO - Contents: 08:44:51 INFO - build_url:https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 08:44:52 INFO - Running post-action listener: _resource_record_post_action 08:44:52 INFO - Running post-action listener: set_extra_try_arguments 08:44:52 INFO - [mozharness: 2016-09-10 15:44:52.669083Z] Finished download-and-extract step (success) 08:44:52 INFO - [mozharness: 2016-09-10 15:44:52.669315Z] Running populate-webroot step. 08:44:52 INFO - Running pre-action listener: _resource_record_pre_action 08:44:52 INFO - Running main action method: populate_webroot 08:44:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:52 INFO - '--tppagecycles', 08:44:52 INFO - '7'], 08:44:52 INFO - 'tests': ['tsvgm']}}, 08:44:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['damp', 'tps']}, 08:44:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'tests': ['damp', 'tps']}, 08:44:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['dromaeo_dom']}, 08:44:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['basic_compositor_video']}, 08:44:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:44:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore', 08:44:52 INFO - 'tabpaint']}, 08:44:52 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore', 08:44:52 INFO - 'tabpaint']}, 08:44:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore', 08:44:52 INFO - 'tabpaint']}, 08:44:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore', 08:44:52 INFO - 'tabpaint']}, 08:44:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore', 08:44:52 INFO - 'tabpaint']}, 08:44:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore', 08:44:52 INFO - 'tabpaint']}, 08:44:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['tsvgx', 08:44:52 INFO - 'tsvgr_opacity', 08:44:52 INFO - 'tart', 08:44:52 INFO - 'tscrollx', 08:44:52 INFO - 'cart']}, 08:44:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:52 INFO - 'tsvgr_opacity', 08:44:52 INFO - 'tart', 08:44:52 INFO - 'tscrollx', 08:44:52 INFO - 'cart']}, 08:44:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--disable-e10s'], 08:44:52 INFO - 'tests': ['tp5o']}, 08:44:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'tests': ['tp5o']}, 08:44:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--disable-e10s', 08:44:52 INFO - '--xperf_path', 08:44:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:52 INFO - 'tests': ['tp5n']}, 08:44:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--xperf_path', 08:44:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:52 INFO - 'tests': ['tp5n']}}, 08:44:52 INFO - 'talos.zip': {'path': '', 08:44:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:52 INFO - Running post-action listener: _resource_record_post_action 08:44:52 INFO - [mozharness: 2016-09-10 15:44:52.689522Z] Finished populate-webroot step (success) 08:44:52 INFO - [mozharness: 2016-09-10 15:44:52.689611Z] Running create-virtualenv step. 08:44:52 INFO - Running pre-action listener: _resource_record_pre_action 08:44:52 INFO - Running main action method: create_virtualenv 08:44:52 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:52 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 08:44:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:52 INFO - Using real prefix '/usr' 08:44:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:53 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:55 INFO - Installing pip.................done. 08:44:55 INFO - Return code: 0 08:44:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:44:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:44:55 INFO - Using env: {'DISPLAY': ':0', 08:44:55 INFO - 'HOME': '/home/cltbld', 08:44:55 INFO - 'LANG': 'en_US.UTF-8', 08:44:55 INFO - 'LANGUAGE': 'en_US:en', 08:44:55 INFO - 'LOGNAME': 'cltbld', 08:44:55 INFO - 'MAIL': '/var/mail/cltbld', 08:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:55 INFO - 'MOZ_NO_REMOTE': '1', 08:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:55 INFO - 'NO_EM_RESTART': '1', 08:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:55 INFO - 'PWD': '/builds/slave/test', 08:44:55 INFO - 'SHELL': '/bin/bash', 08:44:55 INFO - 'SHLVL': '1', 08:44:55 INFO - 'TERM': 'linux', 08:44:55 INFO - 'TMOUT': '86400', 08:44:55 INFO - 'USER': 'cltbld', 08:44:55 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:55 INFO - '_': '/tools/buildbot/bin/python'} 08:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:55 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 08:44:55 INFO - Cleaning up... 08:44:55 INFO - Return code: 0 08:44:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:44:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:44:55 INFO - Using env: {'DISPLAY': ':0', 08:44:55 INFO - 'HOME': '/home/cltbld', 08:44:55 INFO - 'LANG': 'en_US.UTF-8', 08:44:55 INFO - 'LANGUAGE': 'en_US:en', 08:44:55 INFO - 'LOGNAME': 'cltbld', 08:44:55 INFO - 'MAIL': '/var/mail/cltbld', 08:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:55 INFO - 'MOZ_NO_REMOTE': '1', 08:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:55 INFO - 'NO_EM_RESTART': '1', 08:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:55 INFO - 'PWD': '/builds/slave/test', 08:44:55 INFO - 'SHELL': '/bin/bash', 08:44:55 INFO - 'SHLVL': '1', 08:44:55 INFO - 'TERM': 'linux', 08:44:55 INFO - 'TMOUT': '86400', 08:44:55 INFO - 'USER': 'cltbld', 08:44:55 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:55 INFO - '_': '/tools/buildbot/bin/python'} 08:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:55 INFO - Downloading/unpacking psutil>=3.1.1 08:44:55 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 08:44:55 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 08:44:55 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 08:44:55 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 08:44:55 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 08:44:55 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 08:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:57 INFO - Installing collected packages: psutil 08:44:57 INFO - Running setup.py install for psutil 08:44:57 INFO - building 'psutil._psutil_linux' extension 08:44:57 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 08:44:57 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 08:44:57 INFO - building 'psutil._psutil_posix' extension 08:44:57 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 08:44:58 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 08:44:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:58 INFO - Successfully installed psutil 08:44:58 INFO - Cleaning up... 08:44:58 INFO - Return code: 0 08:44:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:58 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 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:44:58 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 08:44:58 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 08:44:58 INFO - Using env: {'DISPLAY': ':0', 08:44:58 INFO - 'HOME': '/home/cltbld', 08:44:58 INFO - 'LANG': 'en_US.UTF-8', 08:44:58 INFO - 'LANGUAGE': 'en_US:en', 08:44:58 INFO - 'LOGNAME': 'cltbld', 08:44:58 INFO - 'MAIL': '/var/mail/cltbld', 08:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:58 INFO - 'MOZ_NO_REMOTE': '1', 08:44:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:58 INFO - 'NO_EM_RESTART': '1', 08:44:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:58 INFO - 'PWD': '/builds/slave/test', 08:44:58 INFO - 'SHELL': '/bin/bash', 08:44:58 INFO - 'SHLVL': '1', 08:44:58 INFO - 'TERM': 'linux', 08:44:58 INFO - 'TMOUT': '86400', 08:44:58 INFO - 'USER': 'cltbld', 08:44:58 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:58 INFO - '_': '/tools/buildbot/bin/python'} 08:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:45:00 INFO - Installing collected packages: mozsystemmonitor 08:45:00 INFO - Running setup.py install for mozsystemmonitor 08:45:00 INFO - Successfully installed mozsystemmonitor 08:45:00 INFO - Cleaning up... 08:45:00 INFO - Return code: 0 08:45:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:45:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:45:00 INFO - Using env: {'DISPLAY': ':0', 08:45:00 INFO - 'HOME': '/home/cltbld', 08:45:00 INFO - 'LANG': 'en_US.UTF-8', 08:45:00 INFO - 'LANGUAGE': 'en_US:en', 08:45:00 INFO - 'LOGNAME': 'cltbld', 08:45:00 INFO - 'MAIL': '/var/mail/cltbld', 08:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:00 INFO - 'MOZ_NO_REMOTE': '1', 08:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:00 INFO - 'NO_EM_RESTART': '1', 08:45:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:00 INFO - 'PWD': '/builds/slave/test', 08:45:00 INFO - 'SHELL': '/bin/bash', 08:45:00 INFO - 'SHLVL': '1', 08:45:00 INFO - 'TERM': 'linux', 08:45:00 INFO - 'TMOUT': '86400', 08:45:00 INFO - 'USER': 'cltbld', 08:45:00 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:00 INFO - '_': '/tools/buildbot/bin/python'} 08:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:00 INFO - Downloading/unpacking jsonschema==2.5.1 08:45:00 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 08:45:00 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 08:45:00 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 08:45:00 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 08:45:00 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 08:45:00 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 08:45:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:45:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:02 INFO - Downloading functools32-3.2.3-2.tar.gz 08:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:45:02 INFO - warning: no files found matching '*.txt' 08:45:02 INFO - no previously-included directories found matching 'build' 08:45:02 INFO - no previously-included directories found matching 'dist' 08:45:02 INFO - no previously-included directories found matching '.git*' 08:45:02 INFO - Installing collected packages: jsonschema, functools32 08:45:02 INFO - Running setup.py install for functools32 08:45:03 INFO - warning: no files found matching '*.txt' 08:45:03 INFO - no previously-included directories found matching 'build' 08:45:03 INFO - no previously-included directories found matching 'dist' 08:45:03 INFO - no previously-included directories found matching '.git*' 08:45:03 INFO - Successfully installed jsonschema functools32 08:45:03 INFO - Cleaning up... 08:45:03 INFO - Return code: 0 08:45:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:03 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 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:45:03 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 08:45:03 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 08:45:03 INFO - Using env: {'DISPLAY': ':0', 08:45:03 INFO - 'HOME': '/home/cltbld', 08:45:03 INFO - 'LANG': 'en_US.UTF-8', 08:45:03 INFO - 'LANGUAGE': 'en_US:en', 08:45:03 INFO - 'LOGNAME': 'cltbld', 08:45:03 INFO - 'MAIL': '/var/mail/cltbld', 08:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:03 INFO - 'MOZ_NO_REMOTE': '1', 08:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:03 INFO - 'NO_EM_RESTART': '1', 08:45:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:03 INFO - 'PWD': '/builds/slave/test', 08:45:03 INFO - 'SHELL': '/bin/bash', 08:45:03 INFO - 'SHLVL': '1', 08:45:03 INFO - 'TERM': 'linux', 08:45:03 INFO - 'TMOUT': '86400', 08:45:03 INFO - 'USER': 'cltbld', 08:45:03 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:03 INFO - '_': '/tools/buildbot/bin/python'} 08:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:45:03 INFO - Cleaning up... 08:45:03 INFO - Return code: 0 08:45:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:03 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 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:45:03 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 08:45:03 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 08:45:03 INFO - Using env: {'DISPLAY': ':0', 08:45:03 INFO - 'HOME': '/home/cltbld', 08:45:03 INFO - 'LANG': 'en_US.UTF-8', 08:45:03 INFO - 'LANGUAGE': 'en_US:en', 08:45:03 INFO - 'LOGNAME': 'cltbld', 08:45:03 INFO - 'MAIL': '/var/mail/cltbld', 08:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:03 INFO - 'MOZ_NO_REMOTE': '1', 08:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:03 INFO - 'NO_EM_RESTART': '1', 08:45:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:03 INFO - 'PWD': '/builds/slave/test', 08:45:03 INFO - 'SHELL': '/bin/bash', 08:45:03 INFO - 'SHLVL': '1', 08:45:03 INFO - 'TERM': 'linux', 08:45:03 INFO - 'TMOUT': '86400', 08:45:03 INFO - 'USER': 'cltbld', 08:45:03 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:03 INFO - '_': '/tools/buildbot/bin/python'} 08:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:03 INFO - Downloading/unpacking blobuploader==1.2.4 08:45:03 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 08:45:03 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 08:45:03 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 08:45:03 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 08:45:03 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 08:45:03 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 08:45:05 INFO - Downloading blobuploader-1.2.4.tar.gz 08:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:45:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:45:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:06 INFO - Downloading docopt-0.6.1.tar.gz 08:45:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:45:06 INFO - Installing collected packages: blobuploader, requests, docopt 08:45:06 INFO - Running setup.py install for blobuploader 08:45:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:45:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:45:06 INFO - Running setup.py install for requests 08:45:06 INFO - Running setup.py install for docopt 08:45:06 INFO - Successfully installed blobuploader requests docopt 08:45:06 INFO - Cleaning up... 08:45:06 INFO - Return code: 0 08:45:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:06 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')]} 08:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:06 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')]} 08:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:06 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 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:45:06 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 08:45:06 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 08:45:06 INFO - Using env: {'DISPLAY': ':0', 08:45:06 INFO - 'HOME': '/home/cltbld', 08:45:06 INFO - 'LANG': 'en_US.UTF-8', 08:45:06 INFO - 'LANGUAGE': 'en_US:en', 08:45:06 INFO - 'LOGNAME': 'cltbld', 08:45:06 INFO - 'MAIL': '/var/mail/cltbld', 08:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:06 INFO - 'MOZ_NO_REMOTE': '1', 08:45:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:06 INFO - 'NO_EM_RESTART': '1', 08:45:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:06 INFO - 'PWD': '/builds/slave/test', 08:45:06 INFO - 'SHELL': '/bin/bash', 08:45:06 INFO - 'SHLVL': '1', 08:45:06 INFO - 'TERM': 'linux', 08:45:06 INFO - 'TMOUT': '86400', 08:45:06 INFO - 'USER': 'cltbld', 08:45:06 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:06 INFO - '_': '/tools/buildbot/bin/python'} 08:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:07 INFO - Running setup.py (path:/tmp/pip-bSDlnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:07 INFO - Running setup.py (path:/tmp/pip-RyMCE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:07 INFO - Running setup.py (path:/tmp/pip-I2eq7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:07 INFO - Running setup.py (path:/tmp/pip-NnNG6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:07 INFO - Running setup.py (path:/tmp/pip-LOC_fM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:07 INFO - Running setup.py (path:/tmp/pip-Mxyt8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:07 INFO - Running setup.py (path:/tmp/pip-zlX8iZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:07 INFO - Running setup.py (path:/tmp/pip-5tAnXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:07 INFO - Running setup.py (path:/tmp/pip-w99I75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:07 INFO - Running setup.py (path:/tmp/pip-iheBnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:07 INFO - Running setup.py (path:/tmp/pip-xpmt3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:07 INFO - Running setup.py (path:/tmp/pip-F9pQ4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:07 INFO - Running setup.py (path:/tmp/pip-GOl6Vp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:07 INFO - Running setup.py (path:/tmp/pip-A5ZNMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:08 INFO - Running setup.py (path:/tmp/pip-ZPRgNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:08 INFO - Running setup.py (path:/tmp/pip-MFSjDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:08 INFO - Running setup.py (path:/tmp/pip-VdAuug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:45:08 INFO - Running setup.py install for manifestparser 08:45:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:45:08 INFO - Running setup.py install for mozcrash 08:45:08 INFO - Running setup.py install for mozdebug 08:45:08 INFO - Running setup.py install for mozdevice 08:45:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:45:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:45:08 INFO - Running setup.py install for mozfile 08:45:08 INFO - Running setup.py install for mozhttpd 08:45:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:45:08 INFO - Running setup.py install for mozinfo 08:45:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:45:08 INFO - Running setup.py install for mozInstall 08:45:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Running setup.py install for mozleak 08:45:09 INFO - Running setup.py install for mozlog 08:45:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Running setup.py install for moznetwork 08:45:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Running setup.py install for mozprocess 08:45:09 INFO - Running setup.py install for mozprofile 08:45:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Running setup.py install for mozrunner 08:45:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:45:09 INFO - Running setup.py install for mozscreenshot 08:45:09 INFO - Running setup.py install for moztest 08:45:09 INFO - Running setup.py install for mozversion 08:45:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:45:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:45:10 INFO - Cleaning up... 08:45:10 INFO - Return code: 0 08:45:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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')]} 08:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:10 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 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:45:10 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 08:45:10 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 08:45:10 INFO - Using env: {'DISPLAY': ':0', 08:45:10 INFO - 'HOME': '/home/cltbld', 08:45:10 INFO - 'LANG': 'en_US.UTF-8', 08:45:10 INFO - 'LANGUAGE': 'en_US:en', 08:45:10 INFO - 'LOGNAME': 'cltbld', 08:45:10 INFO - 'MAIL': '/var/mail/cltbld', 08:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:10 INFO - 'MOZ_NO_REMOTE': '1', 08:45:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:10 INFO - 'NO_EM_RESTART': '1', 08:45:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:10 INFO - 'PWD': '/builds/slave/test', 08:45:10 INFO - 'SHELL': '/bin/bash', 08:45:10 INFO - 'SHLVL': '1', 08:45:10 INFO - 'TERM': 'linux', 08:45:10 INFO - 'TMOUT': '86400', 08:45:10 INFO - 'USER': 'cltbld', 08:45:10 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:10 INFO - '_': '/tools/buildbot/bin/python'} 08:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:10 INFO - Running setup.py (path:/tmp/pip-MOdpCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:10 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)) 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:10 INFO - Running setup.py (path:/tmp/pip-tCapEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:10 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)) 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:10 INFO - Running setup.py (path:/tmp/pip-EKeqzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:10 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)) 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:10 INFO - Running setup.py (path:/tmp/pip-lrCCs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:10 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)) 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:10 INFO - Running setup.py (path:/tmp/pip-pslv53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:10 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)) 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:10 INFO - Running setup.py (path:/tmp/pip-eFFOmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:10 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)) 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:10 INFO - Running setup.py (path:/tmp/pip-qCbDJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:10 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)) 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:10 INFO - Running setup.py (path:/tmp/pip-_RFGgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:10 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)) 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:10 INFO - Running setup.py (path:/tmp/pip-jwfSJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:10 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)) 08:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:10 INFO - Running setup.py (path:/tmp/pip-5P4oX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:11 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)) 08:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:11 INFO - Running setup.py (path:/tmp/pip-QiaLZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:11 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)) 08:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:11 INFO - Running setup.py (path:/tmp/pip-_eSXVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:11 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)) 08:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:11 INFO - Running setup.py (path:/tmp/pip-ry58mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:11 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)) 08:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:11 INFO - Running setup.py (path:/tmp/pip-PAkKBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:11 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)) 08:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:11 INFO - Running setup.py (path:/tmp/pip-siNMOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:11 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)) 08:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:11 INFO - Running setup.py (path:/tmp/pip-ZFy5ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:11 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)) 08:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:11 INFO - Running setup.py (path:/tmp/pip-d8PpkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:11 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)) 08:45:11 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)) 08:45:11 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)) 08:45:11 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)) 08:45:11 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)) 08:45:11 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)) 08:45:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:13 INFO - Downloading blessings-1.6.tar.gz 08:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:45:13 INFO - Installing collected packages: blessings 08:45:13 INFO - Running setup.py install for blessings 08:45:13 INFO - Successfully installed blessings 08:45:13 INFO - Cleaning up... 08:45:13 INFO - Return code: 0 08:45:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:45:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:13 INFO - Reading from file tmpfile_stdout 08:45:13 INFO - Current package versions: 08:45:13 INFO - argparse == 1.2.1 08:45:13 INFO - blessings == 1.6 08:45:13 INFO - blobuploader == 1.2.4 08:45:13 INFO - docopt == 0.6.1 08:45:13 INFO - functools32 == 3.2.3-2 08:45:13 INFO - jsonschema == 2.5.1 08:45:13 INFO - manifestparser == 1.1 08:45:13 INFO - mozInstall == 1.12 08:45:13 INFO - mozcrash == 0.17 08:45:13 INFO - mozdebug == 0.1 08:45:13 INFO - mozdevice == 0.48 08:45:13 INFO - mozfile == 1.2 08:45:13 INFO - mozhttpd == 0.7 08:45:13 INFO - mozinfo == 0.9 08:45:13 INFO - mozleak == 0.1 08:45:13 INFO - mozlog == 3.3 08:45:13 INFO - moznetwork == 0.27 08:45:13 INFO - mozprocess == 0.23 08:45:13 INFO - mozprofile == 0.28 08:45:13 INFO - mozrunner == 6.12 08:45:13 INFO - mozscreenshot == 0.1 08:45:13 INFO - mozsystemmonitor == 0.3 08:45:13 INFO - moztest == 0.7 08:45:13 INFO - mozversion == 1.4 08:45:13 INFO - psutil == 3.1.1 08:45:13 INFO - requests == 1.2.3 08:45:13 INFO - wsgiref == 0.1.2 08:45:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:13 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')]} 08:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:13 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')]} 08:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:13 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 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:45:13 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 08:45:13 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 08:45:13 INFO - Using env: {'DISPLAY': ':0', 08:45:13 INFO - 'HOME': '/home/cltbld', 08:45:13 INFO - 'LANG': 'en_US.UTF-8', 08:45:13 INFO - 'LANGUAGE': 'en_US:en', 08:45:13 INFO - 'LOGNAME': 'cltbld', 08:45:13 INFO - 'MAIL': '/var/mail/cltbld', 08:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:13 INFO - 'MOZ_NO_REMOTE': '1', 08:45:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:13 INFO - 'NO_EM_RESTART': '1', 08:45:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:13 INFO - 'PWD': '/builds/slave/test', 08:45:13 INFO - 'SHELL': '/bin/bash', 08:45:13 INFO - 'SHLVL': '1', 08:45:13 INFO - 'TERM': 'linux', 08:45:13 INFO - 'TMOUT': '86400', 08:45:13 INFO - 'USER': 'cltbld', 08:45:13 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:13 INFO - '_': '/tools/buildbot/bin/python'} 08:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:14 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)) 08:45:14 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)) 08:45:14 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)) 08:45:14 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)) 08:45:14 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)) 08:45:14 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)) 08:45:14 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)) 08:45:14 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)) 08:45:14 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)) 08:45:14 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)) 08:45:14 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)) 08:45:14 INFO - Cleaning up... 08:45:14 INFO - Return code: 0 08:45:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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')]} 08:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:14 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 0x1910030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, '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': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', '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 08:45:14 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 08:45:14 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 08:45:14 INFO - Using env: {'DISPLAY': ':0', 08:45:14 INFO - 'HOME': '/home/cltbld', 08:45:14 INFO - 'LANG': 'en_US.UTF-8', 08:45:14 INFO - 'LANGUAGE': 'en_US:en', 08:45:14 INFO - 'LOGNAME': 'cltbld', 08:45:14 INFO - 'MAIL': '/var/mail/cltbld', 08:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:14 INFO - 'MOZ_NO_REMOTE': '1', 08:45:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:14 INFO - 'NO_EM_RESTART': '1', 08:45:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:14 INFO - 'PWD': '/builds/slave/test', 08:45:14 INFO - 'SHELL': '/bin/bash', 08:45:14 INFO - 'SHLVL': '1', 08:45:14 INFO - 'TERM': 'linux', 08:45:14 INFO - 'TMOUT': '86400', 08:45:14 INFO - 'USER': 'cltbld', 08:45:14 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:14 INFO - '_': '/tools/buildbot/bin/python'} 08:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:45:14 INFO - Cleaning up... 08:45:14 INFO - Return code: 0 08:45:14 INFO - Running post-action listener: _resource_record_post_action 08:45:14 INFO - Running post-action listener: _start_resource_monitoring 08:45:14 INFO - Starting resource monitoring. 08:45:14 INFO - [mozharness: 2016-09-10 15:45:14.317788Z] Finished create-virtualenv step (success) 08:45:14 INFO - [mozharness: 2016-09-10 15:45:14.318222Z] Running install step. 08:45:14 INFO - Running pre-action listener: _resource_record_pre_action 08:45:14 INFO - Running main action method: install 08:45:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:14 INFO - Reading from file tmpfile_stdout 08:45:14 INFO - Detecting whether we're running mozinstall >=1.0... 08:45:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:45:14 INFO - Reading from file tmpfile_stdout 08:45:14 INFO - Output received: 08:45:14 INFO - Usage: mozinstall [options] installer 08:45:14 INFO - Options: 08:45:14 INFO - -h, --help show this help message and exit 08:45:14 INFO - -d DEST, --destination=DEST 08:45:14 INFO - Directory to install application into. [default: 08:45:14 INFO - "/builds/slave/test"] 08:45:14 INFO - --app=APP Application being installed. [default: firefox] 08:45:14 INFO - mkdir: /builds/slave/test/build/application 08:45:14 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'] 08:45:14 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 08:45:31 INFO - Reading from file tmpfile_stdout 08:45:31 INFO - Output received: 08:45:31 INFO - /builds/slave/test/build/application/firefox/firefox 08:45:31 INFO - Running post-action listener: _resource_record_post_action 08:45:31 INFO - [mozharness: 2016-09-10 15:45:31.042272Z] Finished install step (success) 08:45:31 INFO - [mozharness: 2016-09-10 15:45:31.042553Z] Running run-tests step. 08:45:31 INFO - Running pre-action listener: _resource_record_pre_action 08:45:31 INFO - Running main action method: run_tests 08:45:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:31 INFO - Python 2.7.3 08:45:31 INFO - Return code: 0 08:45:31 INFO - grabbing minidump binary from tooltool 08:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:31 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 0x1bcf3b0>, '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 0x1bd02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:31 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 08:45:31 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 08:45:31 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 08:45:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:31 INFO - Return code: 0 08:45:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:45:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:45:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:31 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/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 08:45:31 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/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 08:45:31 INFO - Using env: {'DISPLAY': ':0', 08:45:31 INFO - 'HOME': '/home/cltbld', 08:45:31 INFO - 'LANG': 'en_US.UTF-8', 08:45:31 INFO - 'LANGUAGE': 'en_US:en', 08:45:31 INFO - 'LOGNAME': 'cltbld', 08:45:31 INFO - 'MAIL': '/var/mail/cltbld', 08:45:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:31 INFO - 'MOZ_NO_REMOTE': '1', 08:45:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:31 INFO - 'NO_EM_RESTART': '1', 08:45:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:31 INFO - 'PWD': '/builds/slave/test', 08:45:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:31 INFO - 'SHELL': '/bin/bash', 08:45:31 INFO - 'SHLVL': '1', 08:45:31 INFO - 'TERM': 'linux', 08:45:31 INFO - 'TMOUT': '86400', 08:45:31 INFO - 'USER': 'cltbld', 08:45:31 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319', 08:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:31 INFO - '_': '/tools/buildbot/bin/python'} 08:45:31 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/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 08:45:31 INFO - mozversion application_buildid: 20160910055116 08:45:31 INFO - mozversion application_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 08:45:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:45:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:31 INFO - mozversion application_name: Firefox 08:45:31 INFO - mozversion application_remotingname: firefox-dev 08:45:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:45:31 INFO - mozversion application_vendor: Mozilla 08:45:31 INFO - mozversion application_version: 50.0a2 08:45:31 INFO - mozversion platform_buildid: 20160910055116 08:45:31 INFO - mozversion platform_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 08:45:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:45:31 INFO - mozversion platform_version: 50.0a2 08:45:31 INFO - using testdate: 1473522331 08:45:31 INFO - actual date: 1473522331 08:45:31 INFO - starting webserver on 'localhost:42284' 08:45:31 INFO - SUITE-START | Running 2 tests 08:45:31 INFO - TEST-START | dromaeo_css 08:45:31 INFO - operating with platform_type : linux_ 08:45:31 INFO - Initialising browser for dromaeo_css test... 08:45:31 INFO - TEST-INFO | started process 27948 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQM7vJa/profile http://localhost:42284/getInfo.html) 08:45:35 INFO - PROCESS | 27948 | __metrics Screen width/height:1600/1200 08:45:35 INFO - PROCESS | 27948 | colorDepth:24 08:45:35 INFO - PROCESS | 27948 | Browser inner width/height: 1024/697 08:45:35 INFO - PROCESS | 27948 | __metrics 08:45:36 INFO - PROCESS | 27948 | 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" 08:45:36 INFO - PROCESS | 27948 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:45:37 INFO - TEST-INFO | 27948: exit 0 08:45:37 INFO - Browser initialized. 08:45:37 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:37 INFO - TEST-INFO | started process 28038 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQM7vJa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:49 INFO - PROCESS | 28038 | RSS: Main: 146132992 08:45:49 INFO - PROCESS | 28038 | 08:46:40 INFO - PROCESS | 28038 | 1473522400149 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] 08:48:49 INFO - PROCESS | 28038 | Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42284/tests/dromaeo/cssquery-ext.html) 08:48:50 INFO - PROCESS | 28038 | RSS: Main: 221753344 08:48:50 INFO - PROCESS | 28038 | 08:51:52 INFO - PROCESS | 28038 | Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-ext.html (next: http://localhost:42284/tests/dromaeo/cssquery-jquery.html) 08:51:53 INFO - PROCESS | 28038 | RSS: Main: 236224512 08:51:53 INFO - PROCESS | 28038 | 08:54:55 INFO - PROCESS | 28038 | Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42284/tests/dromaeo/cssquery-mootools.html) 08:54:55 INFO - PROCESS | 28038 | RSS: Main: 178106368 08:54:55 INFO - PROCESS | 28038 | 08:57:57 INFO - PROCESS | 28038 | Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42284/tests/dromaeo/cssquery-prototype.html) 08:57:58 INFO - PROCESS | 28038 | RSS: Main: 177119232 08:57:58 INFO - PROCESS | 28038 | 09:01:01 INFO - PROCESS | 28038 | Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42284/tests/dromaeo/cssquery-yui.html) 09:01:01 INFO - PROCESS | 28038 | RSS: Main: 178184192 09:01:01 INFO - PROCESS | 28038 | 09:04:04 INFO - PROCESS | 28038 | Cycle 1(1): loaded http://localhost:42284/tests/dromaeo/cssquery-yui.html (next: http://localhost:42284/tests/dromaeo/cssquery-dojo.html) 09:04:04 INFO - PROCESS | 28038 | RSS: Main: 266125312 09:04:04 INFO - PROCESS | 28038 | 09:04:04 INFO - PROCESS | 28038 | __start_tp_report 09:04:04 INFO - PROCESS | 28038 | _x_x_mozilla_page_load 09:04:04 INFO - PROCESS | 28038 | _x_x_mozilla_page_load_details 09:04:04 INFO - PROCESS | 28038 | |i|pagename|runs| 09:04:04 INFO - PROCESS | 28038 | |0;dojo.html;3194.648588655248;3212.59842519685;3234.660360662129;3262.8857989970325;3285.687859653332;5538.390777014531;5597.104463285875;5627.831165065044;5630.436956304377;5646.74589643466;5576.888462230753;5583.134614134816;5597.048501754702;5608.495235428821;5629.043062679344;5059.367579052619;5061.6962982221;5080.13637681595;5100.260462232974;5134.589232861362;5104.106781313255;5193.948060519384;5208.140656791625;5208.635395522315;5213.504717051874;5003.74981250936;5071.898562028758;5102.1836506159025;5112.744362781846;5177.559907407875;5155.484451554833;5173.068847607429;5177.870553236199;5183.533481986639;5204.68771873685;4936.284238785356;5032.370953630796;5068.746562671889;5071.594272458195;5077.238414237857;5440.809571664972;5454.290942177531;5467.152591348347;5475.616706830523;5484.7531861066345;5936.020556608154;5946.732397042144;5954.166416701665;5956.404359564052;5956.493698035672;5900.321463031763;5913.349531551526;5922.082077278029;5958.672273024982;5991.101334799815;5589.860253493695;5592.916106258369;5594.104943209149;5599.160125981095;5619.072852979253;6099.420555047263;6101.542384321194;6103.206583144163;6108.205933228652;6108.450239478469;5777.566682498829;5852.385499522489;5902.76388944446;5912.763489460373;5969.611975221596;6010.158577799111;6012.248468941382;6012.488938440194;6014.939850601526;6017.488513476404;5992.370801065913;6000.819975400745;6001.099835024694;6009.06859436788;6012.158297838306;5804.535637149018;5851.61964472308;5895.55783316253;5900.350961394329;5974.551908606872;4534.6599005074095;4562.406887104655;4563.224251877189;4565.246734288871;4592.97703511487;4076.9388459173138;4080.4287399764057;4086.734362266443;4097.528784189797;4117.238310912491;5604.075327570987;5707.400722924116;5758.107493338539;5772.191893134965;5828.766849326064;6091.908621370683;6100.816975490742;6104.3590423005835;6106.511479081662;6111.297200821962;5807.157962095473;5816.76732930678;5819.476247137778;5822.592418530747;5826.213461182686;5125.718085505258;5159.2003239497935;5202.297689811827;5202.7918883245;5266.052110620049;6196.442320191204;6211.130441738161;6227.408396202444;6229.813105606839;6239.282482514478;5978.730957107027;6007.3992600739575;6017.819465416044;6038.5169186465855;6040.305364883095;6121.117656725397;6135.509159267159;6164.907526387018;6165.198524191826;6218.875622487615;5937.079752638348;5946.970265148647;5949.7620095197435;5950.821475355746;5988.37122102173;6022.518198544193;6022.879542409215;6033.668148251888;6038.033914573647;6051.152838602688;5755.798547050833;5811.4188581143235;5888.381719919518;5893.528517718658;5970.432808883149;5872.765089396376;5913.083472061838;5914.704264786657;5920.496757775637;5924.11138329254;5802.970985145047;5813.593048486735;5831.300243970751;5847.766089356548;5904.261967254093;5782.3639399666845;5826.096954971986;5845.210896528914;5847.239858818326;5856.824295271149;5727.11229759388;5730.484256416776;5737;5737.368889422077;5743.253377060955 09:04:04 INFO - PROCESS | 28038 | |1;ext.html;609.0498821837933;648.1606319815838;674.4503229867657;691.1913061717787;701.3897908819328;35672.46491302629;47597.76201118998;47624.52375476244;47859.52140478594;47936;48919.75540122303;48970.265446018246;49368.25947610789;49371.25943110856;49416.50583494173;8378.874316885252;9260.53697315135;9397.389169703982;9406.247500199968;10971.780564388708;10862.67411977638;11288.604898828542;11293.604723834667;11294.040006599449;11302.491387887523;11352.48913798881;11392.430378481084;11396.430178491084;11402.543898244061;11411.087113030937;11285.492152853141;11323.830142547786;11330.263532870336;11338.48976796042;11363.431828408546;11138.27601205919;11226.382548959804;11230.15773816967;11237.381943993078;11239.100871930226;45618;45831.31253031207;45966.310505342444;45969.77015114903;46043;3928.5285765708013;3952.4664170336923;3963.742356746802;4168.187203495319;4554.111948170108;35704;35952.46071308933;36109.81945090284;37107.257854843025;37271.62728372736;4482.9551704482865;4690.202665546865;4770.499097594738;4973.179425394806;5018.974905125451;368559;368764;369921;370531.9999999973;372140;63839;64355;64357.67821160958;64363.356366436674;64453.67773161104;39144.608553914666;40066.79966600148;40140.19719605592;41848.581514184465;41941.58058419438;17370.739438908426;17477.7378339325;17572.297108115534;17583.73624395635;17594.560135996704;12265.386730663431;12594.622161335175;12652.557160499338;12939.417726202344;13256.668583285495;20127.39617811468;20913.163473460892;21031.57936841285;21574.568508629738;21639.35081947544;7831.647575858986;7840;7882.684692612232;7983.560904150328;8024.398170137147;1032.4063663393579;1032.726327523207;1103.0789290942153;1120.4621781544904;1159.686884541169;32625;33025.174370640925;33148.834255828566;33388.3322333552;33929.49105763416;1097.4512743628186;1177.8527684039495;1207.2334067866968;1241.6399244219256;1245.7819881520697;17526.474205773848;17718;17722.20250088749;17795.110244487725;17874.5531361717;586332;590783;592609;593564;594823;63568.046479302844;64148.358516415174;64230.35769642337;64302;64375.67812161003;1375.5460697969645;1385.2519639394816;1390.6940473095904;1465.8021167142513;1480.7112613040354;29592;36573;36915.446268306536;37012.44481332728;37170;55244.72377638086;55694.16458753041;55927;55933.440665592825;55984.88030239291;454912;455492;455677;455949;456839;27828.304292392044;28644.713552864203;29602.55596166016;29738.702612973593;31612;52305.7384713074;53136;53551.464485356206;53607.46392536024;53875.461245388615;43883;44066.55933440624;44658.3301250488;45501.54498454973;46085.30872036855;42141.78929105335;46240;47113.764431177624;47166.52833471759;47251.291230632254;45016.32475512935;45952.540474594825;46026.30960535662;46081.539184609064;47105.52894471012;36866.44700329444;40270.1810873236;40865.59134408737;41005.79497102495;41547.7922610385;29899.850500748227;30214.69785302207;30230.395392091596;30586.84706576453;30643.54034689526 09:04:04 INFO - PROCESS | 28038 | |2;jquery.html;408.61385990239233;414.9792510374481;423.9003834098986;427.529717310958;444.3445917272022;20078.99999999998;21166.576668466623;21701.67447488287;21963.780362196372;22090.56411333262;19678.11448484818;20186.899065504687;21107.68338474924;21325.36023919285;21372.57254854902;5450.700211488366;5710.828675139732;5716.657000579979;5717.085266357383;5718.027935250997;5425.701586412746;5482.506574408302;5559.416261292567;5559.749811258503;5597.132444471114;5534.0592099343;5566.276384070065;5566.4990150886415;5567.8886422271535;5568.136938774496;5509.972450137744;6040.969795151018;6089.969550152266;6092.847678808043;6093.268807743055;5330.92003619946;5529.198266251411;5533;5535.612507124503;5540.22436858836;15425.922870385688;15950.521484355371;20139.79860201394;20395.388138355873;20632.793672063388;2556.067035532036;2599.3111825366295;2638.3008502746598;2644.2728249731285;2790.7906906982057;10544;10551.472426378727;10831.24181307301;10993.945030274877;11046.889531104747;1914.731937528747;1915.6264528416964;1936.66108431024;1971.753530808649;2027.3512476007684;89771.55114224387;90254.5487272566;91283.54358228233;91512.08487915102;92138.5393073037;9441.339106262632;9940.155086817556;9954.850677239847;10230.948845255874;10925.399103049249;14542.927285363505;14618.342174602169;14622.63443413902;14643.341049652578;14667.119972801665;8221.218984196492;8234.135415781257;8257.8348433031;8366.247037766509;8481;8191.549464779494;8273.131321211185;8279.8344033119;8384.538850363166;8452.239298463044;4445.177642136216;4477.708948918375;4502.890201943855;4563.361129441881;4606.424196975378;2062.6390381683145;2083.697863809739;2092.8116469517513;2099.4870571394354;2151.817095546896;1021.6781713760141;1026.3277553202665;1028.2699283508643;1079.0558261521169;1085.1861104171871;20898.47753806167;21093.89453052725;21202.15191392327;21206.15175392967;21362.465938351354;996.8155891160159;1008.702432782331;1046.5238316565944;1073.5866691323897;1102.7739313440725;11381.829272560919;11820.23168494045;11895.464704088337;11924.46339914706;11945.283283003047;507178;509651;515086;521113;526420;97142;99657.50171249098;101054.49472752736;103710;104709.47645261725;1020.3673722292078;1042.6142327338782;1055.0926203465015;1055.5091882274626;1073.511552243743;18104.366347177787;18348;18967.336143235472;19056.415451308734;19311.130999105357;42866.78566607147;44296;45474.772626136655;46594.301085484425;46830.53169468262;61195.38804611897;63824.68087659532;64998;65353.34646653663;65471.67264163648;8724.389292749447;8889.244414224864;8979.730608081769;9029.548522573976;9321.30090243248;11496.712582185177;13512.864871351161;13698.246596437291;13724.313784310943;13957;11674.533018679498;11839.881601183877;11993.280403175837;12164.695882603006;12646.683832903884;11845.052395808143;12129.514819407124;12190.51237950472;12289.324087174933;12330.71881701888;11672.591459298883;11681.415929203336;12152.75694486123;12423.875761242634;12437.62687119388;7774.183710710294;8023.117457079833;8064.879026814485;8313.875291870747;9225.12361325673;11001.669949901516;12020.0383969284;12447.626571202878;12448.377581121089;12569.685757856127 09:04:04 INFO - PROCESS | 28038 | |3;mootools.html;1396.8044473773666;1563.9843601563985;1595.6010997250687;1747.4058820001196;1842.0145222306069;9248.260139188866;9635.903640963588;9695.563699633516;9965.800683986316;9971.401715897051;9728.805423891536;9833.311668183213;9849.916509184002;9860.457674827881;9861.359011664237;2553.5531282025663;2600.3109176068356;2600.4669042846267;2600.8699565021766;2612.294680436281;2404.939876503093;2457.3242358351417;2475.5420247254224;2475.591527397977;2477.5664258754737;2459.4958033603157;2473.703155621328;2530.8861101250486;2535.1127105513015;2544.325753675268;2454.0674543673467;2459.090136649433;2491.115653942851;2503.0113105323294;2510.6736124303907;2437.3297343230574;2445.5475736988715;2452.5340185364726;2455.58255096634;2496.750324967507;9514.619415223382;9678.128968392879;9754.707358779247;9817.312788104835;9834.606615735362;5584.692841893694;5750.309962804448;5797.420257974211;5817.534597232254;5831.650100993954;3427.2631384252504;3449.413599688059;3476.217850983534;3530.770499917497;3549.716022718202;1178.9823152652716;1190.2917763930448;1205.8733832947503;1228;2348.2250857217077;13160.60518184456;13448.32758362078;13834.446622135103;13939.372728227152;13966.092204006809;5099.490050994871;5151.304573882553;5256.39551451588;5295.920561191585;5340.519353258148;12006.939965300118;12008.819867701992;12024.579139730242;12027.45876435545;12031.15781895259;5633.6619802811965;5633.802816901389;5659.717014149276;5666;5676.68778217202;4652.069586082797;5138.768755406016;5158.767855446514;5222.712750798667;5310.044192045469;4332.891677708082;4354.586314300136;4464.866054018385;4465.441819772528;4516.932246016312;1380.378829526697;1401.3063533550958;1409.7391982483273;1414.4695739097838;1417.2771886338041;1053.8577292065627;1054.288355360129;1060.48566445274;1062.5855916192536;1081.648464249122;6333.714982825784;6496.967515162489;6597.934020659828;6695.899561506581;6710.530262881649;479.3145801503883;484.3171128708504;488.96332775042015;497.10354995691324;499.7950840155519;4300.849470268588;4427.667924905645;4433.889152771142;4474.32885067236;4504.076664283814;11957.162998590014;12297.508099675913;12322.137450378566;12435.316057616737;12449.564265250672;11274.718132046764;11715.88284117165;11747.353895535656;12113.515459381526;12344.81482777759;8851.645934162561;9058.773530661654;9112.726618201465;9123.315751318678;9127.817443651089;6551.21385433751;6765.424938880054;6777.491688123312;6812.727490900308;6835.965820170867;10996.790353060996;11120.666380008613;11245;11281.661550153509;11313.71715707114;11003.17476189273;11296.265742726697;11564.537418503165;11578.36319002463;11640.476178572155;7770.45606807518;7990.081140668665;8005.319547838499;8746.781330466789;8903.599338029675;8800.603972821367;9372;9411.152996230097;9730.221582273543;9734.318597698093;8377.162283771575;8489.193526614961;8511;8548.871766923377;8737.344699147463;8301.128381519853;8369.95815020921;8425.452345597516;8440.53577053268;8535.743927682179;7323.63381830917;7397.149327827154;7419.8145046374275;7458.552486850806;7503.362214211853;5649.5762817789655;5714.457126572969;5814.76740930358;5927.14056461802;5951.851203719942;5820.185174075548;5850.151727999501;5892.469677729025;5897.262842144732;5979.641221526722 09:04:04 INFO - PROCESS | 28038 | |4;prototype.html;350.8789467633667;356.72532150244297;356.8519064588196;357.76387584194435;363.0977022100081;5398.730063496829;5408.513233808956;5435.266239057726;5442.700651464173;5464.180372944061;5334;5356.517913387795;5402.135658294674;5409.323834520685;5434.288199217244;3876.7092468064866;3950.604939506041;4060.817263223147;4228.112096459732;4265.976165720236;4224.155168966204;4226.852060177879;4232.365145228209;4250.936235956463;4260.446142001551;4211.305134652778;4245.9787701061605;4247.08687632159;4261.78691065447;4290.292101803198;3959;4010.11777408971;4059.127287633173;4126.3397856343145;4269.188854117708;3761.3417651910804;3772.5472943246987;3797.316483033053;3799.5820459749407;3799.9810000950097;5236.73816309183;5249.1338929076655;5257.737113144328;5282.2076688496645;5360.437154098802;4752.738599377033;4834.081524510332;4844.273358996179;4866.31871537985;4900.852974410738;5602.1596760485845;5617.410171931929;5623.887522249532;5624.831255062314;5631.352394474606;4145.461090058303;4145.689073319483;4152.667786577066;4161.791910404498;4180.184863951531;5584.301962254718;5704.172894930253;5709.373688103436;5914.491804589802;5927.73325200367;4183.728057676241;4208.716341515806;4230.894227644272;4238.660907127422;4252.893677658021;4295.78521073945;4296.699231053796;4304.567919125684;4316.460442444694;4318.805653745589;4746.504850972002;4869.610431165498;4884.413870335511;4907.067657145131;4907.1167189905445;5656.2929633795775;5657.405972372924;5682.431756824367;5686.715664216773;5767.653940763567;1442.2500299844023;1451.1003178029478;1455.2287287737515;1458.4239225506078;1462.5758530026174;934.8737920380797;956.7273327101742;957.712686194139;968.7142293023575;987.8715766950394;4932.186189278729;4935.654504184745;4944.777485013183;4973.085362135604;4977;5181.771838109574;5182.486494050382;5187.351581052369;5190.97404512975;5199.584033277404;4664.323673067454;4895;4963.205887058053;4969.229769385751;4988.251762235694;2691.515527205122;2695.353115252325;2702.608121822325;2706.4316493536576;2722.3602453423428;5234.869128271824;5240.764165612557;5322;5349.786008559614;5353.839384818461;5211.71335576547;5230.933781156422;5477.507024367826;5958.165856780054;5963.344032156459;6057.757689692451;6066.7269972850445;6068.21113255274;6069.635821850703;6075.969620151959;5573.303337082864;5856.94143058564;5857.970710146422;5862.794802181903;5875.588708790344;5731.598788084794;5745.482906538432;5770.2498675171955;5788.7395067222915;5801.535877129904;5801;5887.38097023322;6015.789447369321;6057.878842423215;6071.878562428815;4187.895302617337;4235.538739134993;4242.300020496584;4242.639375652981;4256.53178150397;5674.659520428788;5860.706964651836;5870.530357571299;5872.94127058741;5880.970595146997;5659.858503537444;5672.773089076556;5672.865426914716;5692.288463942007;5710.914336285042;5682.886342273214;5684.317881854204;5692.7722891083895;5703.230063941315;5703.942960570507;5517.613767036269;5626.099824028136;5644.435556444323;5659.490645841894;5676.148577713211;5709.259555413341;5712.714364281686;5719.428057194414;5720.713964301852;5725.770969161187;5576.386597474194;5578.442155784389;5590.468905454138;5591.8881622368135;5602.383737788921 09:04:04 INFO - PROCESS | 28038 | |5;yui.html;794.360539765489;803.0925054688203;810.5784991804262;854.3084692309229;886.1271647427279;16500;17301.480955571322;17761.46715598531;17920.910395448005;17964.461066168;15094.396224151022;16201.594960125973;16281.348746050144;16333.509994700178;16369.83630163698;903.8644203369481;911.731039343392;917.807260475301;919.3380765848611;921.7050543825978;903.9728808135767;906.4153620914507;906.6328137104483;907.4237858959542;908.209857424042;965.9661911833088;969.8593703912928;971.3540495570472;972.042538099971;973.4402718436892;966.0484422843493;966.8549717542389;966.8694726211977;967.2552134856156;967.4147140979746;712.4620911212054;712.8716830970424;713.0160378677443;713.2760248347931;713.6003837850768;14669.633259168499;14728.705425891421;14748.631284217872;14782.70434591313;14836.3323650436;13888.375023123883;14433.711325773425;14735.631609209748;15097.169655668933;15618.297176626966;14393.352299146563;14449.78325325121;14575;15018.699626007528;15178.24108794567;4861;4876.171050921352;4903.289023091667;4933.087378834927;4985.576226020793;79051.6047419765;90723.99999999935;91646;93509.5324523373;93529;12427.130100893031;12565.560205392767;12709.491620335082;12812.551560695518;12873.806892896613;23872;23961.880190599284;24027;24058.87970560136;24140;9119.361644684941;9200.677976270932;9362.719118426457;9469;9514.238860891111;5085.313482679865;5177.741112944338;5239.842804715865;5267.604929630293;5342.198670199501;3974.82113304902;4133.297339452301;4135.875923722293;4144.694421257294;4147.875563733093;699.8145491444772;708.674009955416;711.3917600451642;714.8355878148055;726.9963650181716;673.6665350651459;682.9043933849265;801.8917446144812;805.8509175802495;842.9199226073636;9343.672971445967;10554.366737995591;10593.629222977159;10731.624393146358;10750.193735469715;670.8691805098057;730.5689643110591;752.1087511299128;754.3625636337287;791.7505985614628;4867.928095567456;4936.827211047596;4945.085159245479;4977.477364876709;4986.426560945465;121235.39382303032;129152;129457;132001;133932;71757.2824271761;75675;92478.53760731153;102617;107497.4625126885;80433;92232.99999999866;94945;94998;97223;10057.49712514386;10261.58953641846;10695.197860160364;11209.215354925076;11422.828657569977;49766;58044.129338059116;58937.41062589319;61039.28998902431;62554.687226563576;86873.56563217143;87073;87313.56343218243;88457;88496.557517212;15508.689826203186;15616.21918904073;15901.761473577673;15956.441524546584;16016.439424620083;15832.841671583137;16114.597135071717;16433.506994790176;16932.15339233058;17173.828261717223;15642.687146257238;16057.598560036093;16173.272202751139;16222.26999784989;16241.918790405973;15212.771808422662;15715.371385144466;15877.2855221513;15897.920510397375;16222.918885405497;14455.566333010027;14889.181095039878;15178.165200914056;15196.924015379853;15793.921030395235;12149.69625759363;12616.495340186657;13044.478220871439;13139.160517527745;13417.463301468222;12986.480540778262;13165.275909825052;13260.053219268437;13346.399412026676;13454.730905382034 09:04:04 INFO - PROCESS | 28038 | __end_tp_report 09:04:04 INFO - PROCESS | 28038 | __start_cc_report 09:04:04 INFO - PROCESS | 28038 | _x_x_mozilla_cycle_collect,1066 09:04:04 INFO - PROCESS | 28038 | __end_cc_report 09:04:04 INFO - PROCESS | 28038 | __startTimestamp1473523444558__endTimestamp 09:04:04 INFO - PROCESS | 28038 | 09:04:04 INFO - PROCESS | 28038 | ------- Summary: start ------- 09:04:04 INFO - PROCESS | 28038 | Number of tests: 6 09:04:04 INFO - PROCESS | 28038 | 09:04:04 INFO - PROCESS | 28038 | [#0] dojo.html Cycles:165 Average:5573.82 Median:5816.77 stddev:631.99 (10.9%) stddev-sans-first:605.74 09:04:04 INFO - PROCESS | 28038 | Values: 3194.6 3212.6 3234.7 3262.9 3285.7 5538.4 5597.1 5627.8 5630.4 5646.7 5576.9 5583.1 5597.0 5608.5 5629.0 5059.4 5061.7 5080.1 5100.3 5134.6 5104.1 5193.9 5208.1 5208.6 5213.5 5003.7 5071.9 5102.2 5112.7 5177.6 5155.5 5173.1 5177.9 5183.5 5204.7 4936.3 5032.4 5068.7 5071.6 5077.2 5440.8 5454.3 5467.2 5475.6 5484.8 5936.0 5946.7 5954.2 5956.4 5956.5 5900.3 5913.3 5922.1 5958.7 5991.1 5589.9 5592.9 5594.1 5599.2 5619.1 6099.4 6101.5 6103.2 6108.2 6108.5 5777.6 5852.4 5902.8 5912.8 5969.6 6010.2 6012.2 6012.5 6014.9 6017.5 5992.4 6000.8 6001.1 6009.1 6012.2 5804.5 5851.6 5895.6 5900.4 5974.6 4534.7 4562.4 4563.2 4565.2 4593.0 4076.9 4080.4 4086.7 4097.5 4117.2 5604.1 5707.4 5758.1 5772.2 5828.8 6091.9 6100.8 6104.4 6106.5 6111.3 5807.2 5816.8 5819.5 5822.6 5826.2 5125.7 5159.2 5202.3 5202.8 5266.1 6196.4 6211.1 6227.4 6229.8 6239.3 5978.7 6007.4 6017.8 6038.5 6040.3 6121.1 6135.5 6164.9 6165.2 6218.9 5937.1 5947.0 5949.8 5950.8 5988.4 6022.5 6022.9 6033.7 6038.0 6051.2 5755.8 5811.4 5888.4 5893.5 5970.4 5872.8 5913.1 5914.7 5920.5 5924.1 5803.0 5813.6 5831.3 5847.8 5904.3 5782.4 5826.1 5845.2 5847.2 5856.8 5727.1 5730.5 5737.0 5737.4 5743.3 09:04:04 INFO - PROCESS | 28038 | 09:04:04 INFO - PROCESS | 28038 | [#1] ext.html Cycles:180 Average:64517.16 Median:3958.10 stddev:127560.93 (3222.8%) stddev-sans-first:127828.53 09:04:04 INFO - PROCESS | 28038 | Values: 609.0 648.2 674.5 691.2 701.4 35672.5 47597.8 47624.5 47859.5 47936.0 48919.8 48970.3 49368.3 49371.3 49416.5 8378.9 9260.5 9397.4 9406.2 10971.8 10862.7 11288.6 11293.6 11294.0 11302.5 11352.5 11392.4 11396.4 11402.5 11411.1 11285.5 11323.8 11330.3 11338.5 11363.4 11138.3 11226.4 11230.2 11237.4 11239.1 45618.0 45831.3 45966.3 45969.8 46043.0 3928.5 3952.5 3963.7 4168.2 4554.1 35704.0 35952.5 36109.8 37107.3 37271.6 4483.0 4690.2 4770.5 4973.2 5019.0 368559.0 368764.0 369921.0 370532.0 372140.0 63839.0 64355.0 64357.7 64363.4 64453.7 39144.6 40066.8 40140.2 41848.6 41941.6 17370.7 17477.7 17572.3 17583.7 17594.6 12265.4 12594.6 12652.6 12939.4 13256.7 20127.4 20913.2 21031.6 21574.6 21639.4 7831.6 7840.0 7882.7 7983.6 8024.4 1032.4 1032.7 1103.1 1120.5 1159.7 32625.0 33025.2 33148.8 33388.3 33929.5 1097.5 1177.9 1207.2 1241.6 1245.8 17526.5 17718.0 17722.2 17795.1 17874.6 586332.0 590783.0 592609.0 593564.0 594823.0 63568.0 64148.4 64230.4 64302.0 64375.7 1375.5 1385.3 1390.7 1465.8 1480.7 29592.0 36573.0 36915.4 37012.4 37170.0 55244.7 55694.2 55927.0 55933.4 55984.9 454912.0 455492.0 455677.0 455949.0 456839.0 27828.3 28644.7 29602.6 29738.7 31612.0 52305.7 53136.0 53551.5 53607.5 53875.5 43883.0 44066.6 44658.3 45501.5 46085.3 42141.8 46240.0 47113.8 47166.5 47251.3 45016.3 45952.5 46026.3 46081.5 47105.5 36866.4 40270.2 40865.6 41005.8 41547.8 29899.9 30214.7 30230.4 30586.8 30643.5 09:04:04 INFO - PROCESS | 28038 | 09:04:04 INFO - PROCESS | 28038 | [#2] jquery.html Cycles:180 Average:30664.81 Median:11926.75 stddev:85368.93 (715.8%) stddev-sans-first:85578.19 09:04:04 INFO - PROCESS | 28038 | Values: 408.6 415.0 423.9 427.5 444.3 20079.0 21166.6 21701.7 21963.8 22090.6 19678.1 20186.9 21107.7 21325.4 21372.6 5450.7 5710.8 5716.7 5717.1 5718.0 5425.7 5482.5 5559.4 5559.7 5597.1 5534.1 5566.3 5566.5 5567.9 5568.1 5510.0 6041.0 6090.0 6092.8 6093.3 5330.9 5529.2 5533.0 5535.6 5540.2 15425.9 15950.5 20139.8 20395.4 20632.8 2556.1 2599.3 2638.3 2644.3 2790.8 10544.0 10551.5 10831.2 10993.9 11046.9 1914.7 1915.6 1936.7 1971.8 2027.4 89771.6 90254.5 91283.5 91512.1 92138.5 9441.3 9940.2 9954.9 10230.9 10925.4 14542.9 14618.3 14622.6 14643.3 14667.1 8221.2 8234.1 8257.8 8366.2 8481.0 8191.5 8273.1 8279.8 8384.5 8452.2 4445.2 4477.7 4502.9 4563.4 4606.4 2062.6 2083.7 2092.8 2099.5 2151.8 1021.7 1026.3 1028.3 1079.1 1085.2 20898.5 21093.9 21202.2 21206.2 21362.5 996.8 1008.7 1046.5 1073.6 1102.8 11381.8 11820.2 11895.5 11924.5 11945.3 507178.0 509651.0 515086.0 521113.0 526420.0 97142.0 99657.5 101054.5 103710.0 104709.5 1020.4 1042.6 1055.1 1055.5 1073.5 18104.4 18348.0 18967.3 19056.4 19311.1 42866.8 44296.0 45474.8 46594.3 46830.5 61195.4 63824.7 64998.0 65353.3 65471.7 8724.4 8889.2 8979.7 9029.5 9321.3 11496.7 13512.9 13698.2 13724.3 13957.0 11674.5 11839.9 11993.3 12164.7 12646.7 11845.1 12129.5 12190.5 12289.3 12330.7 11672.6 11681.4 12152.8 12423.9 12437.6 7774.2 8023.1 8064.9 8313.9 9225.1 11001.7 12020.0 12447.6 12448.4 12569.7 09:04:04 INFO - PROCESS | 28038 | 09:04:04 INFO - PROCESS | 28038 | [#3] mootools.html Cycles:180 Average:6400.15 Median:2565.69 stddev:3714.43 (144.8%) stddev-sans-first:3705.82 09:04:04 INFO - PROCESS | 28038 | Values: 1396.8 1564.0 1595.6 1747.4 1842.0 9248.3 9635.9 9695.6 9965.8 9971.4 9728.8 9833.3 9849.9 9860.5 9861.4 2553.6 2600.3 2600.5 2600.9 2612.3 2404.9 2457.3 2475.5 2475.6 2477.6 2459.5 2473.7 2530.9 2535.1 2544.3 2454.1 2459.1 2491.1 2503.0 2510.7 2437.3 2445.5 2452.5 2455.6 2496.8 9514.6 9678.1 9754.7 9817.3 9834.6 5584.7 5750.3 5797.4 5817.5 5831.7 3427.3 3449.4 3476.2 3530.8 3549.7 1179.0 1190.3 1205.9 1228.0 2348.2 13160.6 13448.3 13834.4 13939.4 13966.1 5099.5 5151.3 5256.4 5295.9 5340.5 12006.9 12008.8 12024.6 12027.5 12031.2 5633.7 5633.8 5659.7 5666.0 5676.7 4652.1 5138.8 5158.8 5222.7 5310.0 4332.9 4354.6 4464.9 4465.4 4516.9 1380.4 1401.3 1409.7 1414.5 1417.3 1053.9 1054.3 1060.5 1062.6 1081.6 6333.7 6497.0 6597.9 6695.9 6710.5 479.3 484.3 489.0 497.1 499.8 4300.8 4427.7 4433.9 4474.3 4504.1 11957.2 12297.5 12322.1 12435.3 12449.6 11274.7 11715.9 11747.4 12113.5 12344.8 8851.6 9058.8 9112.7 9123.3 9127.8 6551.2 6765.4 6777.5 6812.7 6836.0 10996.8 11120.7 11245.0 11281.7 11313.7 11003.2 11296.3 11564.5 11578.4 11640.5 7770.5 7990.1 8005.3 8746.8 8903.6 8800.6 9372.0 9411.2 9730.2 9734.3 8377.2 8489.2 8511.0 8548.9 8737.3 8301.1 8370.0 8425.5 8440.5 8535.7 7323.6 7397.1 7419.8 7458.6 7503.4 5649.6 5714.5 5814.8 5927.1 5951.9 5820.2 5850.2 5892.5 5897.3 5979.6 09:04:04 INFO - PROCESS | 28038 | 09:04:04 INFO - PROCESS | 28038 | [#4] prototype.html Cycles:180 Average:4704.40 Median:5253.44 stddev:1378.79 (26.2%) stddev-sans-first:1343.38 09:04:04 INFO - PROCESS | 28038 | Values: 350.9 356.7 356.9 357.8 363.1 5398.7 5408.5 5435.3 5442.7 5464.2 5334.0 5356.5 5402.1 5409.3 5434.3 3876.7 3950.6 4060.8 4228.1 4266.0 4224.2 4226.9 4232.4 4250.9 4260.4 4211.3 4246.0 4247.1 4261.8 4290.3 3959.0 4010.1 4059.1 4126.3 4269.2 3761.3 3772.5 3797.3 3799.6 3800.0 5236.7 5249.1 5257.7 5282.2 5360.4 4752.7 4834.1 4844.3 4866.3 4900.9 5602.2 5617.4 5623.9 5624.8 5631.4 4145.5 4145.7 4152.7 4161.8 4180.2 5584.3 5704.2 5709.4 5914.5 5927.7 4183.7 4208.7 4230.9 4238.7 4252.9 4295.8 4296.7 4304.6 4316.5 4318.8 4746.5 4869.6 4884.4 4907.1 4907.1 5656.3 5657.4 5682.4 5686.7 5767.7 1442.3 1451.1 1455.2 1458.4 1462.6 934.9 956.7 957.7 968.7 987.9 4932.2 4935.7 4944.8 4973.1 4977.0 5181.8 5182.5 5187.4 5191.0 5199.6 4664.3 4895.0 4963.2 4969.2 4988.3 2691.5 2695.4 2702.6 2706.4 2722.4 5234.9 5240.8 5322.0 5349.8 5353.8 5211.7 5230.9 5477.5 5958.2 5963.3 6057.8 6066.7 6068.2 6069.6 6076.0 5573.3 5856.9 5858.0 5862.8 5875.6 5731.6 5745.5 5770.2 5788.7 5801.5 5801.0 5887.4 6015.8 6057.9 6071.9 4187.9 4235.5 4242.3 4242.6 4256.5 5674.7 5860.7 5870.5 5872.9 5881.0 5659.9 5672.8 5672.9 5692.3 5710.9 5682.9 5684.3 5692.8 5703.2 5703.9 5517.6 5626.1 5644.4 5659.5 5676.1 5709.3 5712.7 5719.4 5720.7 5725.8 5576.4 5578.4 5590.5 5591.9 5602.4 09:04:04 INFO - PROCESS | 28038 | 09:04:04 INFO - PROCESS | 28038 | [#5] yui.html Cycles:180 Average:22875.61 Median:4504.44 stddev:32480.54 (721.1%) stddev-sans-first:32529.34 09:04:04 INFO - PROCESS | 28038 | Values: 794.4 803.1 810.6 854.3 886.1 16500.0 17301.5 17761.5 17920.9 17964.5 15094.4 16201.6 16281.3 16333.5 16369.8 903.9 911.7 917.8 919.3 921.7 904.0 906.4 906.6 907.4 908.2 966.0 969.9 971.4 972.0 973.4 966.0 966.9 966.9 967.3 967.4 712.5 712.9 713.0 713.3 713.6 14669.6 14728.7 14748.6 14782.7 14836.3 13888.4 14433.7 14735.6 15097.2 15618.3 14393.4 14449.8 14575.0 15018.7 15178.2 4861.0 4876.2 4903.3 4933.1 4985.6 79051.6 90724.0 91646.0 93509.5 93529.0 12427.1 12565.6 12709.5 12812.6 12873.8 23872.0 23961.9 24027.0 24058.9 24140.0 9119.4 9200.7 9362.7 9469.0 9514.2 5085.3 5177.7 5239.8 5267.6 5342.2 3974.8 4133.3 4135.9 4144.7 4147.9 699.8 708.7 711.4 714.8 727.0 673.7 682.9 801.9 805.9 842.9 9343.7 10554.4 10593.6 10731.6 10750.2 670.9 730.6 752.1 754.4 791.8 4867.9 4936.8 4945.1 4977.5 4986.4 121235.4 129152.0 129457.0 132001.0 133932.0 71757.3 75675.0 92478.5 102617.0 107497.5 80433.0 92233.0 94945.0 94998.0 97223.0 10057.5 10261.6 10695.2 11209.2 11422.8 49766.0 58044.1 58937.4 61039.3 62554.7 86873.6 87073.0 87313.6 88457.0 88496.6 15508.7 15616.2 15901.8 15956.4 16016.4 15832.8 16114.6 16433.5 16932.2 17173.8 15642.7 16057.6 16173.3 16222.3 16241.9 15212.8 15715.4 15877.3 15897.9 16222.9 14455.6 14889.2 15178.2 15196.9 15793.9 12149.7 12616.5 13044.5 13139.2 13417.5 12986.5 13165.3 13260.1 13346.4 13454.7 09:04:04 INFO - PROCESS | 28038 | -------- Summary: end -------- 09:04:04 INFO - PROCESS | 28038 | 09:04:05 INFO - TEST-INFO | 28038: exit 0 09:04:05 INFO - TEST-OK | dromaeo_css | took 1114002ms 09:04:05 INFO - TEST-START | kraken 09:04:05 INFO - operating with platform_type : linux_ 09:04:05 INFO - Initialising browser for kraken test... 09:04:05 INFO - TEST-INFO | started process 28247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnNc7Yg/profile http://localhost:42284/getInfo.html) 09:04:09 INFO - PROCESS | 28247 | __metrics Screen width/height:1600/1200 09:04:09 INFO - PROCESS | 28247 | colorDepth:24 09:04:09 INFO - PROCESS | 28247 | Browser inner width/height: 1024/697 09:04:09 INFO - PROCESS | 28247 | __metrics 09:04:10 INFO - PROCESS | 28247 | 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" 09:04:10 INFO - PROCESS | 28247 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:04:10 INFO - PROCESS | 28247 | console.error: PushService: 09:04:10 INFO - PROCESS | 28247 | stateChangeProcessEnqueue: Error transitioning state 09:04:10 INFO - PROCESS | 28247 | UnknownError 09:04:11 INFO - TEST-INFO | 28247: exit 0 09:04:11 INFO - Browser initialized. 09:04:11 INFO - Running cycle 1/1 for kraken test... 09:04:11 INFO - TEST-INFO | started process 28334 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnNc7Yg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:23 INFO - PROCESS | 28334 | RSS: Main: 145440768 09:04:23 INFO - PROCESS | 28334 | 09:04:25 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42284/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:26 INFO - PROCESS | 28334 | RSS: Main: 200654848 09:04:26 INFO - PROCESS | 28334 | 09:04:28 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42284/tests/kraken/driver.html?testName=audio-dft) 09:04:29 INFO - PROCESS | 28334 | RSS: Main: 185102336 09:04:29 INFO - PROCESS | 28334 | 09:04:32 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42284/tests/kraken/driver.html?testName=audio-fft) 09:04:32 INFO - PROCESS | 28334 | RSS: Main: 275357696 09:04:32 INFO - PROCESS | 28334 | 09:04:34 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42284/tests/kraken/driver.html?testName=audio-oscillator) 09:04:35 INFO - PROCESS | 28334 | RSS: Main: 182177792 09:04:35 INFO - PROCESS | 28334 | 09:04:37 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42284/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:38 INFO - PROCESS | 28334 | RSS: Main: 180109312 09:04:38 INFO - PROCESS | 28334 | 09:04:41 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42284/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:41 INFO - PROCESS | 28334 | RSS: Main: 215896064 09:04:41 INFO - PROCESS | 28334 | 09:04:45 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42284/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:45 INFO - PROCESS | 28334 | RSS: Main: 214548480 09:04:45 INFO - PROCESS | 28334 | 09:04:48 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42284/tests/kraken/driver.html?testName=json-parse-financial) 09:04:49 INFO - PROCESS | 28334 | RSS: Main: 214540288 09:04:49 INFO - PROCESS | 28334 | 09:04:50 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42284/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:51 INFO - PROCESS | 28334 | RSS: Main: 177831936 09:04:51 INFO - PROCESS | 28334 | 09:04:53 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:53 INFO - PROCESS | 28334 | RSS: Main: 185982976 09:04:53 INFO - PROCESS | 28334 | 09:04:55 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:56 INFO - PROCESS | 28334 | RSS: Main: 186548224 09:04:56 INFO - PROCESS | 28334 | 09:04:58 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:04:59 INFO - PROCESS | 28334 | RSS: Main: 181518336 09:04:59 INFO - PROCESS | 28334 | 09:05:02 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:05:02 INFO - PROCESS | 28334 | RSS: Main: 184438784 09:05:02 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | Cycle 1(1): loaded http://localhost:42284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42284/tests/kraken/driver.html?testName=ai-astar) 09:05:04 INFO - PROCESS | 28334 | RSS: Main: 235581440 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | __start_tp_report 09:05:04 INFO - PROCESS | 28334 | _x_x_mozilla_page_load 09:05:04 INFO - PROCESS | 28334 | _x_x_mozilla_page_load_details 09:05:04 INFO - PROCESS | 28334 | |i|pagename|runs| 09:05:04 INFO - PROCESS | 28334 | |0;ai-astar;112;112;119;110;112;110;109;111;112;111 09:05:04 INFO - PROCESS | 28334 | |1;audio-beat-detection;142;115;147;176;121;144;141;122;162;115 09:05:04 INFO - PROCESS | 28334 | |2;audio-dft;159;160;161;162;161;162;161;161;162;161 09:05:04 INFO - PROCESS | 28334 | |3;audio-fft;75;75;74;183;76;74;75;74;164;76 09:05:04 INFO - PROCESS | 28334 | |4;audio-oscillator;83;83;154;83;83;83;143;83;83;146 09:05:04 INFO - PROCESS | 28334 | |5;imaging-gaussian-blur;117;116;117;117;116;116;116;116;117;117 09:05:04 INFO - PROCESS | 28334 | |6;imaging-darkroom;146;146;147;145;147;146;145;146;146;146 09:05:04 INFO - PROCESS | 28334 | |7;imaging-desaturate;95;95;95;95;94;95;95;95;95;95 09:05:04 INFO - PROCESS | 28334 | |8;json-parse-financial;50;52;49;50;49;53;51;50;50;51 09:05:04 INFO - PROCESS | 28334 | |9;json-stringify-tinderbox;71;60;60;59;59;60;60;61;61;60 09:05:04 INFO - PROCESS | 28334 | |10;stanford-crypto-aes;72;72;72;71;77;72;78;72;72;73 09:05:04 INFO - PROCESS | 28334 | |11;stanford-crypto-ccm;138;117;116;117;117;115;122;116;160;126 09:05:04 INFO - PROCESS | 28334 | |12;stanford-crypto-pbkdf2;151;153;155;157;149;152;152;153;151;150 09:05:04 INFO - PROCESS | 28334 | |13;stanford-crypto-sha256-iterative;63;61;61;60;63;60;61;63;61;60 09:05:04 INFO - PROCESS | 28334 | __end_tp_report 09:05:04 INFO - PROCESS | 28334 | __start_cc_report 09:05:04 INFO - PROCESS | 28334 | _x_x_mozilla_cycle_collect,1688 09:05:04 INFO - PROCESS | 28334 | __end_cc_report 09:05:04 INFO - PROCESS | 28334 | __startTimestamp1473523504561__endTimestamp 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | ------- Summary: start ------- 09:05:04 INFO - PROCESS | 28334 | Number of tests: 14 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:2.74 (2.4%) stddev-sans-first:2.91 09:05:04 INFO - PROCESS | 28334 | Values: 112.0 112.0 119.0 110.0 112.0 110.0 109.0 111.0 112.0 111.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:143.00 stddev:20.45 (14.3%) stddev-sans-first:21.65 09:05:04 INFO - PROCESS | 28334 | Values: 142.0 115.0 147.0 176.0 121.0 144.0 141.0 122.0 162.0 115.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:0.67 09:05:04 INFO - PROCESS | 28334 | Values: 159.0 160.0 161.0 162.0 161.0 162.0 161.0 161.0 162.0 161.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.00 stddev:41.83 (55.8%) stddev-sans-first:43.76 09:05:04 INFO - PROCESS | 28334 | Values: 75.0 75.0 74.0 183.0 76.0 74.0 75.0 74.0 164.0 76.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:83.00 stddev:31.35 (37.8%) stddev-sans-first:32.46 09:05:04 INFO - PROCESS | 28334 | Values: 83.0 83.0 154.0 83.0 83.0 83.0 143.0 83.0 83.0 146.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:05:04 INFO - PROCESS | 28334 | Values: 117.0 116.0 117.0 117.0 116.0 116.0 116.0 116.0 117.0 117.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:05:04 INFO - PROCESS | 28334 | Values: 146.0 146.0 147.0 145.0 147.0 146.0 145.0 146.0 146.0 146.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:05:04 INFO - PROCESS | 28334 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.50 stddev:1.27 (2.5%) stddev-sans-first:1.33 09:05:04 INFO - PROCESS | 28334 | Values: 50.0 52.0 49.0 50.0 49.0 53.0 51.0 50.0 50.0 51.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:60.00 stddev:3.54 (5.9%) stddev-sans-first:0.71 09:05:04 INFO - PROCESS | 28334 | Values: 71.0 60.0 60.0 59.0 59.0 60.0 60.0 61.0 61.0 60.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:72.00 stddev:2.38 (3.3%) stddev-sans-first:2.49 09:05:04 INFO - PROCESS | 28334 | Values: 72.0 72.0 72.0 71.0 77.0 72.0 78.0 72.0 72.0 73.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:119.50 stddev:14.35 (12.0%) stddev-sans-first:14.36 09:05:04 INFO - PROCESS | 28334 | Values: 138.0 117.0 116.0 117.0 117.0 115.0 122.0 116.0 160.0 126.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:152.50 stddev:2.36 (1.5%) stddev-sans-first:2.46 09:05:04 INFO - PROCESS | 28334 | Values: 151.0 153.0 155.0 157.0 149.0 152.0 152.0 153.0 151.0 150.0 09:05:04 INFO - PROCESS | 28334 | 09:05:04 INFO - PROCESS | 28334 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.00 stddev:1.25 (2.1%) stddev-sans-first:1.17 09:05:04 INFO - PROCESS | 28334 | Values: 63.0 61.0 61.0 60.0 63.0 60.0 61.0 63.0 61.0 60.0 09:05:04 INFO - PROCESS | 28334 | -------- Summary: end -------- 09:05:04 INFO - PROCESS | 28334 | 09:05:05 INFO - TEST-INFO | 28334: exit 0 09:05:05 INFO - TEST-OK | kraken | took 60271ms 09:05:05 INFO - SUITE-END | took 1174s 09:05:05 INFO - Completed test suite (00:19:34) 09:05:05 INFO - javascript benchmark 09:05:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3194.648588655248, 3212.59842519685, 3234.660360662129, 3262.8857989970325, 3285.687859653332, 5538.390777014531, 5597.104463285875, 5627.831165065044, 5630.436956304377, 5646.74589643466, 5576.888462230753, 5583.134614134816, 5597.048501754702, 5608.495235428821, 5629.043062679344, 5059.367579052619, 5061.6962982221, 5080.13637681595, 5100.260462232974, 5134.589232861362, 5104.106781313255, 5193.948060519384, 5208.140656791625, 5208.635395522315, 5213.504717051874, 5003.74981250936, 5071.898562028758, 5102.1836506159025, 5112.744362781846, 5177.559907407875, 5155.484451554833, 5173.068847607429, 5177.870553236199, 5183.533481986639, 5204.68771873685, 4936.284238785356, 5032.370953630796, 5068.746562671889, 5071.594272458195, 5077.238414237857, 5440.809571664972, 5454.290942177531, 5467.152591348347, 5475.616706830523, 5484.7531861066345, 5936.020556608154, 5946.732397042144, 5954.166416701665, 5956.404359564052, 5956.493698035672, 5900.321463031763, 5913.349531551526, 5922.082077278029, 5958.672273024982, 5991.101334799815, 5589.860253493695, 5592.916106258369, 5594.104943209149, 5599.160125981095, 5619.072852979253, 6099.420555047263, 6101.542384321194, 6103.206583144163, 6108.205933228652, 6108.450239478469, 5777.566682498829, 5852.385499522489, 5902.76388944446, 5912.763489460373, 5969.611975221596, 6010.158577799111, 6012.248468941382, 6012.488938440194, 6014.939850601526, 6017.488513476404, 5992.370801065913, 6000.819975400745, 6001.099835024694, 6009.06859436788, 6012.158297838306, 5804.535637149018, 5851.61964472308, 5895.55783316253, 5900.350961394329, 5974.551908606872, 4534.6599005074095, 4562.406887104655, 4563.224251877189, 4565.246734288871, 4592.97703511487, 4076.9388459173138, 4080.4287399764057, 4086.734362266443, 4097.528784189797, 4117.238310912491, 5604.075327570987, 5707.400722924116, 5758.107493338539, 5772.191893134965, 5828.766849326064, 6091.908621370683, 6100.816975490742, 6104.3590423005835, 6106.511479081662, 6111.297200821962, 5807.157962095473, 5816.76732930678, 5819.476247137778, 5822.592418530747, 5826.213461182686, 5125.718085505258, 5159.2003239497935, 5202.297689811827, 5202.7918883245, 5266.052110620049, 6196.442320191204, 6211.130441738161, 6227.408396202444, 6229.813105606839, 6239.282482514478, 5978.730957107027, 6007.3992600739575, 6017.819465416044, 6038.5169186465855, 6040.305364883095, 6121.117656725397, 6135.509159267159, 6164.907526387018, 6165.198524191826, 6218.875622487615, 5937.079752638348, 5946.970265148647, 5949.7620095197435, 5950.821475355746, 5988.37122102173, 6022.518198544193, 6022.879542409215, 6033.668148251888, 6038.033914573647, 6051.152838602688, 5755.798547050833, 5811.4188581143235, 5888.381719919518, 5893.528517718658, 5970.432808883149, 5872.765089396376, 5913.083472061838, 5914.704264786657, 5920.496757775637, 5924.11138329254, 5802.970985145047, 5813.593048486735, 5831.300243970751, 5847.766089356548, 5904.261967254093, 5782.3639399666845, 5826.096954971986, 5845.210896528914, 5847.239858818326, 5856.824295271149, 5727.11229759388, 5730.484256416776, 5737.0, 5737.368889422077, 5743.253377060955], "value": 5530.9017771013905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.0498821837933, 648.1606319815838, 674.4503229867657, 691.1913061717787, 701.3897908819328, 35672.46491302629, 47597.76201118998, 47624.52375476244, 47859.52140478594, 47936.0, 48919.75540122303, 48970.265446018246, 49368.25947610789, 49371.25943110856, 49416.50583494173, 8378.874316885252, 9260.53697315135, 9397.389169703982, 9406.247500199968, 10971.780564388708, 10862.67411977638, 11288.604898828542, 11293.604723834667, 11294.040006599449, 11302.491387887523, 11352.48913798881, 11392.430378481084, 11396.430178491084, 11402.543898244061, 11411.087113030937, 11285.492152853141, 11323.830142547786, 11330.263532870336, 11338.48976796042, 11363.431828408546, 11138.27601205919, 11226.382548959804, 11230.15773816967, 11237.381943993078, 11239.100871930226, 45618.0, 45831.31253031207, 45966.310505342444, 45969.77015114903, 46043.0, 3928.5285765708013, 3952.4664170336923, 3963.742356746802, 4168.187203495319, 4554.111948170108, 35704.0, 35952.46071308933, 36109.81945090284, 37107.257854843025, 37271.62728372736, 4482.9551704482865, 4690.202665546865, 4770.499097594738, 4973.179425394806, 5018.974905125451, 368559.0, 368764.0, 369921.0, 370531.9999999973, 372140.0, 63839.0, 64355.0, 64357.67821160958, 64363.356366436674, 64453.67773161104, 39144.608553914666, 40066.79966600148, 40140.19719605592, 41848.581514184465, 41941.58058419438, 17370.739438908426, 17477.7378339325, 17572.297108115534, 17583.73624395635, 17594.560135996704, 12265.386730663431, 12594.622161335175, 12652.557160499338, 12939.417726202344, 13256.668583285495, 20127.39617811468, 20913.163473460892, 21031.57936841285, 21574.568508629738, 21639.35081947544, 7831.647575858986, 7840.0, 7882.684692612232, 7983.560904150328, 8024.398170137147, 1032.4063663393579, 1032.726327523207, 1103.0789290942153, 1120.4621781544904, 1159.686884541169, 32625.0, 33025.174370640925, 33148.834255828566, 33388.3322333552, 33929.49105763416, 1097.4512743628186, 1177.8527684039495, 1207.2334067866968, 1241.6399244219256, 1245.7819881520697, 17526.474205773848, 17718.0, 17722.20250088749, 17795.110244487725, 17874.5531361717, 586332.0, 590783.0, 592609.0, 593564.0, 594823.0, 63568.046479302844, 64148.358516415174, 64230.35769642337, 64302.0, 64375.67812161003, 1375.5460697969645, 1385.2519639394816, 1390.6940473095904, 1465.8021167142513, 1480.7112613040354, 29592.0, 36573.0, 36915.446268306536, 37012.44481332728, 37170.0, 55244.72377638086, 55694.16458753041, 55927.0, 55933.440665592825, 55984.88030239291, 454912.0, 455492.0, 455677.0, 455949.0, 456839.0, 27828.304292392044, 28644.713552864203, 29602.55596166016, 29738.702612973593, 31612.0, 52305.7384713074, 53136.0, 53551.464485356206, 53607.46392536024, 53875.461245388615, 43883.0, 44066.55933440624, 44658.3301250488, 45501.54498454973, 46085.30872036855, 42141.78929105335, 46240.0, 47113.764431177624, 47166.52833471759, 47251.291230632254, 45016.32475512935, 45952.540474594825, 46026.30960535662, 46081.539184609064, 47105.52894471012, 36866.44700329444, 40270.1810873236, 40865.59134408737, 41005.79497102495, 41547.7922610385, 29899.850500748227, 30214.69785302207, 30230.395392091596, 30586.84706576453, 30643.54034689526], "value": 21908.07813678265, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.61385990239233, 414.9792510374481, 423.9003834098986, 427.529717310958, 444.3445917272022, 20078.99999999998, 21166.576668466623, 21701.67447488287, 21963.780362196372, 22090.56411333262, 19678.11448484818, 20186.899065504687, 21107.68338474924, 21325.36023919285, 21372.57254854902, 5450.700211488366, 5710.828675139732, 5716.657000579979, 5717.085266357383, 5718.027935250997, 5425.701586412746, 5482.506574408302, 5559.416261292567, 5559.749811258503, 5597.132444471114, 5534.0592099343, 5566.276384070065, 5566.4990150886415, 5567.8886422271535, 5568.136938774496, 5509.972450137744, 6040.969795151018, 6089.969550152266, 6092.847678808043, 6093.268807743055, 5330.92003619946, 5529.198266251411, 5533.0, 5535.612507124503, 5540.22436858836, 15425.922870385688, 15950.521484355371, 20139.79860201394, 20395.388138355873, 20632.793672063388, 2556.067035532036, 2599.3111825366295, 2638.3008502746598, 2644.2728249731285, 2790.7906906982057, 10544.0, 10551.472426378727, 10831.24181307301, 10993.945030274877, 11046.889531104747, 1914.731937528747, 1915.6264528416964, 1936.66108431024, 1971.753530808649, 2027.3512476007684, 89771.55114224387, 90254.5487272566, 91283.54358228233, 91512.08487915102, 92138.5393073037, 9441.339106262632, 9940.155086817556, 9954.850677239847, 10230.948845255874, 10925.399103049249, 14542.927285363505, 14618.342174602169, 14622.63443413902, 14643.341049652578, 14667.119972801665, 8221.218984196492, 8234.135415781257, 8257.8348433031, 8366.247037766509, 8481.0, 8191.549464779494, 8273.131321211185, 8279.8344033119, 8384.538850363166, 8452.239298463044, 4445.177642136216, 4477.708948918375, 4502.890201943855, 4563.361129441881, 4606.424196975378, 2062.6390381683145, 2083.697863809739, 2092.8116469517513, 2099.4870571394354, 2151.817095546896, 1021.6781713760141, 1026.3277553202665, 1028.2699283508643, 1079.0558261521169, 1085.1861104171871, 20898.47753806167, 21093.89453052725, 21202.15191392327, 21206.15175392967, 21362.465938351354, 996.8155891160159, 1008.702432782331, 1046.5238316565944, 1073.5866691323897, 1102.7739313440725, 11381.829272560919, 11820.23168494045, 11895.464704088337, 11924.46339914706, 11945.283283003047, 507178.0, 509651.0, 515086.0, 521113.0, 526420.0, 97142.0, 99657.50171249098, 101054.49472752736, 103710.0, 104709.47645261725, 1020.3673722292078, 1042.6142327338782, 1055.0926203465015, 1055.5091882274626, 1073.511552243743, 18104.366347177787, 18348.0, 18967.336143235472, 19056.415451308734, 19311.130999105357, 42866.78566607147, 44296.0, 45474.772626136655, 46594.301085484425, 46830.53169468262, 61195.38804611897, 63824.68087659532, 64998.0, 65353.34646653663, 65471.67264163648, 8724.389292749447, 8889.244414224864, 8979.730608081769, 9029.548522573976, 9321.30090243248, 11496.712582185177, 13512.864871351161, 13698.246596437291, 13724.313784310943, 13957.0, 11674.533018679498, 11839.881601183877, 11993.280403175837, 12164.695882603006, 12646.683832903884, 11845.052395808143, 12129.514819407124, 12190.51237950472, 12289.324087174933, 12330.71881701888, 11672.591459298883, 11681.415929203336, 12152.75694486123, 12423.875761242634, 12437.62687119388, 7774.183710710294, 8023.117457079833, 8064.879026814485, 8313.875291870747, 9225.12361325673, 11001.669949901516, 12020.0383969284, 12447.626571202878, 12448.377581121089, 12569.685757856127], "value": 9518.472747425338, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.8044473773666, 1563.9843601563985, 1595.6010997250687, 1747.4058820001196, 1842.0145222306069, 9248.260139188866, 9635.903640963588, 9695.563699633516, 9965.800683986316, 9971.401715897051, 9728.805423891536, 9833.311668183213, 9849.916509184002, 9860.457674827881, 9861.359011664237, 2553.5531282025663, 2600.3109176068356, 2600.4669042846267, 2600.8699565021766, 2612.294680436281, 2404.939876503093, 2457.3242358351417, 2475.5420247254224, 2475.591527397977, 2477.5664258754737, 2459.4958033603157, 2473.703155621328, 2530.8861101250486, 2535.1127105513015, 2544.325753675268, 2454.0674543673467, 2459.090136649433, 2491.115653942851, 2503.0113105323294, 2510.6736124303907, 2437.3297343230574, 2445.5475736988715, 2452.5340185364726, 2455.58255096634, 2496.750324967507, 9514.619415223382, 9678.128968392879, 9754.707358779247, 9817.312788104835, 9834.606615735362, 5584.692841893694, 5750.309962804448, 5797.420257974211, 5817.534597232254, 5831.650100993954, 3427.2631384252504, 3449.413599688059, 3476.217850983534, 3530.770499917497, 3549.716022718202, 1178.9823152652716, 1190.2917763930448, 1205.8733832947503, 1228.0, 2348.2250857217077, 13160.60518184456, 13448.32758362078, 13834.446622135103, 13939.372728227152, 13966.092204006809, 5099.490050994871, 5151.304573882553, 5256.39551451588, 5295.920561191585, 5340.519353258148, 12006.939965300118, 12008.819867701992, 12024.579139730242, 12027.45876435545, 12031.15781895259, 5633.6619802811965, 5633.802816901389, 5659.717014149276, 5666.0, 5676.68778217202, 4652.069586082797, 5138.768755406016, 5158.767855446514, 5222.712750798667, 5310.044192045469, 4332.891677708082, 4354.586314300136, 4464.866054018385, 4465.441819772528, 4516.932246016312, 1380.378829526697, 1401.3063533550958, 1409.7391982483273, 1414.4695739097838, 1417.2771886338041, 1053.8577292065627, 1054.288355360129, 1060.48566445274, 1062.5855916192536, 1081.648464249122, 6333.714982825784, 6496.967515162489, 6597.934020659828, 6695.899561506581, 6710.530262881649, 479.3145801503883, 484.3171128708504, 488.96332775042015, 497.10354995691324, 499.7950840155519, 4300.849470268588, 4427.667924905645, 4433.889152771142, 4474.32885067236, 4504.076664283814, 11957.162998590014, 12297.508099675913, 12322.137450378566, 12435.316057616737, 12449.564265250672, 11274.718132046764, 11715.88284117165, 11747.353895535656, 12113.515459381526, 12344.81482777759, 8851.645934162561, 9058.773530661654, 9112.726618201465, 9123.315751318678, 9127.817443651089, 6551.21385433751, 6765.424938880054, 6777.491688123312, 6812.727490900308, 6835.965820170867, 10996.790353060996, 11120.666380008613, 11245.0, 11281.661550153509, 11313.71715707114, 11003.17476189273, 11296.265742726697, 11564.537418503165, 11578.36319002463, 11640.476178572155, 7770.45606807518, 7990.081140668665, 8005.319547838499, 8746.781330466789, 8903.599338029675, 8800.603972821367, 9372.0, 9411.152996230097, 9730.221582273543, 9734.318597698093, 8377.162283771575, 8489.193526614961, 8511.0, 8548.871766923377, 8737.344699147463, 8301.128381519853, 8369.95815020921, 8425.452345597516, 8440.53577053268, 8535.743927682179, 7323.63381830917, 7397.149327827154, 7419.8145046374275, 7458.552486850806, 7503.362214211853, 5649.5762817789655, 5714.457126572969, 5814.76740930358, 5927.14056461802, 5951.851203719942, 5820.185174075548, 5850.151727999501, 5892.469677729025, 5897.262842144732, 5979.641221526722], "value": 5012.972818036345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.8789467633667, 356.72532150244297, 356.8519064588196, 357.76387584194435, 363.0977022100081, 5398.730063496829, 5408.513233808956, 5435.266239057726, 5442.700651464173, 5464.180372944061, 5334.0, 5356.517913387795, 5402.135658294674, 5409.323834520685, 5434.288199217244, 3876.7092468064866, 3950.604939506041, 4060.817263223147, 4228.112096459732, 4265.976165720236, 4224.155168966204, 4226.852060177879, 4232.365145228209, 4250.936235956463, 4260.446142001551, 4211.305134652778, 4245.9787701061605, 4247.08687632159, 4261.78691065447, 4290.292101803198, 3959.0, 4010.11777408971, 4059.127287633173, 4126.3397856343145, 4269.188854117708, 3761.3417651910804, 3772.5472943246987, 3797.316483033053, 3799.5820459749407, 3799.9810000950097, 5236.73816309183, 5249.1338929076655, 5257.737113144328, 5282.2076688496645, 5360.437154098802, 4752.738599377033, 4834.081524510332, 4844.273358996179, 4866.31871537985, 4900.852974410738, 5602.1596760485845, 5617.410171931929, 5623.887522249532, 5624.831255062314, 5631.352394474606, 4145.461090058303, 4145.689073319483, 4152.667786577066, 4161.791910404498, 4180.184863951531, 5584.301962254718, 5704.172894930253, 5709.373688103436, 5914.491804589802, 5927.73325200367, 4183.728057676241, 4208.716341515806, 4230.894227644272, 4238.660907127422, 4252.893677658021, 4295.78521073945, 4296.699231053796, 4304.567919125684, 4316.460442444694, 4318.805653745589, 4746.504850972002, 4869.610431165498, 4884.413870335511, 4907.067657145131, 4907.1167189905445, 5656.2929633795775, 5657.405972372924, 5682.431756824367, 5686.715664216773, 5767.653940763567, 1442.2500299844023, 1451.1003178029478, 1455.2287287737515, 1458.4239225506078, 1462.5758530026174, 934.8737920380797, 956.7273327101742, 957.712686194139, 968.7142293023575, 987.8715766950394, 4932.186189278729, 4935.654504184745, 4944.777485013183, 4973.085362135604, 4977.0, 5181.771838109574, 5182.486494050382, 5187.351581052369, 5190.97404512975, 5199.584033277404, 4664.323673067454, 4895.0, 4963.205887058053, 4969.229769385751, 4988.251762235694, 2691.515527205122, 2695.353115252325, 2702.608121822325, 2706.4316493536576, 2722.3602453423428, 5234.869128271824, 5240.764165612557, 5322.0, 5349.786008559614, 5353.839384818461, 5211.71335576547, 5230.933781156422, 5477.507024367826, 5958.165856780054, 5963.344032156459, 6057.757689692451, 6066.7269972850445, 6068.21113255274, 6069.635821850703, 6075.969620151959, 5573.303337082864, 5856.94143058564, 5857.970710146422, 5862.794802181903, 5875.588708790344, 5731.598788084794, 5745.482906538432, 5770.2498675171955, 5788.7395067222915, 5801.535877129904, 5801.0, 5887.38097023322, 6015.789447369321, 6057.878842423215, 6071.878562428815, 4187.895302617337, 4235.538739134993, 4242.300020496584, 4242.639375652981, 4256.53178150397, 5674.659520428788, 5860.706964651836, 5870.530357571299, 5872.94127058741, 5880.970595146997, 5659.858503537444, 5672.773089076556, 5672.865426914716, 5692.288463942007, 5710.914336285042, 5682.886342273214, 5684.317881854204, 5692.7722891083895, 5703.230063941315, 5703.942960570507, 5517.613767036269, 5626.099824028136, 5644.435556444323, 5659.490645841894, 5676.148577713211, 5709.259555413341, 5712.714364281686, 5719.428057194414, 5720.713964301852, 5725.770969161187, 5576.386597474194, 5578.442155784389, 5590.468905454138, 5591.8881622368135, 5602.383737788921], "value": 4271.686019015742, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.360539765489, 803.0925054688203, 810.5784991804262, 854.3084692309229, 886.1271647427279, 16500.0, 17301.480955571322, 17761.46715598531, 17920.910395448005, 17964.461066168, 15094.396224151022, 16201.594960125973, 16281.348746050144, 16333.509994700178, 16369.83630163698, 903.8644203369481, 911.731039343392, 917.807260475301, 919.3380765848611, 921.7050543825978, 903.9728808135767, 906.4153620914507, 906.6328137104483, 907.4237858959542, 908.209857424042, 965.9661911833088, 969.8593703912928, 971.3540495570472, 972.042538099971, 973.4402718436892, 966.0484422843493, 966.8549717542389, 966.8694726211977, 967.2552134856156, 967.4147140979746, 712.4620911212054, 712.8716830970424, 713.0160378677443, 713.2760248347931, 713.6003837850768, 14669.633259168499, 14728.705425891421, 14748.631284217872, 14782.70434591313, 14836.3323650436, 13888.375023123883, 14433.711325773425, 14735.631609209748, 15097.169655668933, 15618.297176626966, 14393.352299146563, 14449.78325325121, 14575.0, 15018.699626007528, 15178.24108794567, 4861.0, 4876.171050921352, 4903.289023091667, 4933.087378834927, 4985.576226020793, 79051.6047419765, 90723.99999999935, 91646.0, 93509.5324523373, 93529.0, 12427.130100893031, 12565.560205392767, 12709.491620335082, 12812.551560695518, 12873.806892896613, 23872.0, 23961.880190599284, 24027.0, 24058.87970560136, 24140.0, 9119.361644684941, 9200.677976270932, 9362.719118426457, 9469.0, 9514.238860891111, 5085.313482679865, 5177.741112944338, 5239.842804715865, 5267.604929630293, 5342.198670199501, 3974.82113304902, 4133.297339452301, 4135.875923722293, 4144.694421257294, 4147.875563733093, 699.8145491444772, 708.674009955416, 711.3917600451642, 714.8355878148055, 726.9963650181716, 673.6665350651459, 682.9043933849265, 801.8917446144812, 805.8509175802495, 842.9199226073636, 9343.672971445967, 10554.366737995591, 10593.629222977159, 10731.624393146358, 10750.193735469715, 670.8691805098057, 730.5689643110591, 752.1087511299128, 754.3625636337287, 791.7505985614628, 4867.928095567456, 4936.827211047596, 4945.085159245479, 4977.477364876709, 4986.426560945465, 121235.39382303032, 129152.0, 129457.0, 132001.0, 133932.0, 71757.2824271761, 75675.0, 92478.53760731153, 102617.0, 107497.4625126885, 80433.0, 92232.99999999866, 94945.0, 94998.0, 97223.0, 10057.49712514386, 10261.58953641846, 10695.197860160364, 11209.215354925076, 11422.828657569977, 49766.0, 58044.129338059116, 58937.41062589319, 61039.28998902431, 62554.687226563576, 86873.56563217143, 87073.0, 87313.56343218243, 88457.0, 88496.557517212, 15508.689826203186, 15616.21918904073, 15901.761473577673, 15956.441524546584, 16016.439424620083, 15832.841671583137, 16114.597135071717, 16433.506994790176, 16932.15339233058, 17173.828261717223, 15642.687146257238, 16057.598560036093, 16173.272202751139, 16222.26999784989, 16241.918790405973, 15212.771808422662, 15715.371385144466, 15877.2855221513, 15897.920510397375, 16222.918885405497, 14455.566333010027, 14889.181095039878, 15178.165200914056, 15196.924015379853, 15793.921030395235, 12149.69625759363, 12616.495340186657, 13044.478220871439, 13139.160517527745, 13417.463301468222, 12986.480540778262, 13165.275909825052, 13260.053219268437, 13346.399412026676, 13454.730905382034], "value": 8447.215003778325, "unit": "score"}], "name": "dromaeo_css", "value": 7701.408751301153, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 119.0, 110.0, 112.0, 110.0, 109.0, 111.0, 112.0, 111.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 147.0, 176.0, 121.0, 144.0, 141.0, 122.0, 162.0, 115.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 161.0, 162.0, 161.0, 162.0, 161.0, 161.0, 162.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 183.0, 76.0, 74.0, 75.0, 74.0, 164.0, 76.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 154.0, 83.0, 83.0, 83.0, 143.0, 83.0, 83.0, 146.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 145.0, 147.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 49.0, 50.0, 49.0, 53.0, 51.0, 50.0, 50.0, 51.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 60.0, 60.0, 59.0, 59.0, 60.0, 60.0, 61.0, 61.0, 60.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 71.0, 77.0, 72.0, 78.0, 72.0, 72.0, 73.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 117.0, 116.0, 117.0, 117.0, 115.0, 122.0, 116.0, 160.0, 126.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 155.0, 157.0, 149.0, 152.0, 152.0, 153.0, 151.0, 150.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 60.0, 63.0, 60.0, 61.0, 63.0, 61.0, 60.0], "value": 61.3, "unit": "score"}], "name": "kraken", "value": 1488.3999999999999, "alertThreshold": 2.0}]} 09:05:05 INFO - Return code: 0 09:05:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:05:05 INFO - # TBPL SUCCESS # 09:05:05 INFO - Running post-action listener: _resource_record_post_action 09:05:05 INFO - [mozharness: 2016-09-10 16:05:05.882487Z] Finished run-tests step (success) 09:05:05 INFO - Running post-run listener: _resource_record_post_run 09:05:05 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8192; Write bytes: 328585216; Read time: 428; Write time: 375572 09:05:05 INFO - TinderboxPrint: CPU usage
13.0% 09:05:05 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 428 09:05:05 INFO - TinderboxPrint: I/O write bytes / time
328,585,216 / 375,572 09:05:05 INFO - TinderboxPrint: CPU idle
8,271.5 (87.0%) 09:05:05 INFO - TinderboxPrint: CPU user
1,186.4 (12.5%) 09:05:05 INFO - TinderboxPrint: Swap in / out
0 / 0 09:05:05 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 78729216; Read time: 336; Write time: 131380 09:05:05 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 249241600; Read time: 92; Write time: 244192 09:05:06 INFO - Running post-run listener: _upload_blobber_files 09:05:06 INFO - Blob upload gear active. 09:05:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:05:06 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 09:05:06 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'] 09:05:06 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 09:05:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:05:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:10 INFO - (blobuploader) - INFO - Done attempting. 09:05:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:05:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:05:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:11 INFO - (blobuploader) - INFO - Done attempting. 09:05:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:05:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:05:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:11 INFO - (blobuploader) - INFO - Done attempting. 09:05:11 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:11 INFO - Return code: 0 09:05:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfb28c0c8f041660e0e34b9a523b66c7941ecdd2a9a53c63959211b2639d5c5374965d8c78e768adee3029c93e34fe66ba568c67acd838b6810f38e188006160", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c4c56ba9736f62ce33fa3dd7ffbc4829bbba34caa6dceac07fa2e81cd4b85b3720c9b4ebf62857761d2814f9cf9ecfb1e663200ca4d48e4ae977950b3943e31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d259d340f4a939968829191f7f88a740c14899c2bfebf2f6cabaed86503cbc929089c7a491f8c9e4355e040bd30b89fc64ba55b11a17719acdde4cacc9c4f727"} 09:05:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:11 INFO - Contents: 09:05:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfb28c0c8f041660e0e34b9a523b66c7941ecdd2a9a53c63959211b2639d5c5374965d8c78e768adee3029c93e34fe66ba568c67acd838b6810f38e188006160", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c4c56ba9736f62ce33fa3dd7ffbc4829bbba34caa6dceac07fa2e81cd4b85b3720c9b4ebf62857761d2814f9cf9ecfb1e663200ca4d48e4ae977950b3943e31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d259d340f4a939968829191f7f88a740c14899c2bfebf2f6cabaed86503cbc929089c7a491f8c9e4355e040bd30b89fc64ba55b11a17719acdde4cacc9c4f727"} 09:05:11 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:11 INFO - Copying logs to upload dir... 09:05:11 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.458612 ========= master_lag: 3.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-10 09:05:12.984934) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 09:05:12.987997) ========= 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=fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319 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/cfb28c0c8f041660e0e34b9a523b66c7941ecdd2a9a53c63959211b2639d5c5374965d8c78e768adee3029c93e34fe66ba568c67acd838b6810f38e188006160", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c4c56ba9736f62ce33fa3dd7ffbc4829bbba34caa6dceac07fa2e81cd4b85b3720c9b4ebf62857761d2814f9cf9ecfb1e663200ca4d48e4ae977950b3943e31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d259d340f4a939968829191f7f88a740c14899c2bfebf2f6cabaed86503cbc929089c7a491f8c9e4355e040bd30b89fc64ba55b11a17719acdde4cacc9c4f727"} build_url:https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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/cfb28c0c8f041660e0e34b9a523b66c7941ecdd2a9a53c63959211b2639d5c5374965d8c78e768adee3029c93e34fe66ba568c67acd838b6810f38e188006160", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c4c56ba9736f62ce33fa3dd7ffbc4829bbba34caa6dceac07fa2e81cd4b85b3720c9b4ebf62857761d2814f9cf9ecfb1e663200ca4d48e4ae977950b3943e31", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d259d340f4a939968829191f7f88a740c14899c2bfebf2f6cabaed86503cbc929089c7a491f8c9e4355e040bd30b89fc64ba55b11a17719acdde4cacc9c4f727"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 09:05:13.012311) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 09:05:13.012623) ========= 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=fa44defc139c4ba8574fe040000001db-1473517445.676038-1447087319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 09:05:13.063488) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 09:05:13.063832) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 09:05:13.065021) ========= ========= Total master_lag: 3.23 =========