builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1473388869.98 results: success (0) buildid: 20160908165620 builduid: 4f68f85ea8664afc83719d884046bc56 revision: 7a8b1ac451d942a5faa6f3a9dfa0c3c55eeed155 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:09.978280) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:09.978754) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:09.979038) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.022658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.022939) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-08 19:41:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-09-08 19:41:11 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161450 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.198310) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.198595) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023404 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.247408) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.247866) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7a8b1ac451d942a5faa6f3a9dfa0c3c55eeed155 --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 7a8b1ac451d942a5faa6f3a9dfa0c3c55eeed155 --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=516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-08 19:41:11,238 truncating revision to first 12 chars 2016-09-08 19:41:11,238 Setting DEBUG logging. 2016-09-08 19:41:11,238 attempt 1/10 2016-09-08 19:41:11,238 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7a8b1ac451d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 19:41:11,547 unpacking tar archive at: mozilla-aurora-7a8b1ac451d9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.651212 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.915591) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.915907) ========= script_repo_revision: 7a8b1ac451d942a5faa6f3a9dfa0c3c55eeed155 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.916296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.916560) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 19:41:10.930703) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-08 19:41:10.931009) ========= /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=516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:41:11 INFO - MultiFileLogger online at 20160908 19:41:11 in /builds/slave/test 19:41:11 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 19:41:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:41:12 INFO - {'append_to_log': False, 19:41:12 INFO - 'base_work_dir': '/builds/slave/test', 19:41:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:41:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:41:12 INFO - 'branch': 'Mozilla-Aurora', 19:41:12 INFO - 'buildbot_json_path': 'buildprops.json', 19:41:12 INFO - 'config_files': ('talos/linux_config.py',), 19:41:12 INFO - 'default_actions': ('clobber', 19:41:12 INFO - 'read-buildbot-config', 19:41:12 INFO - 'download-and-extract', 19:41:12 INFO - 'populate-webroot', 19:41:12 INFO - 'create-virtualenv', 19:41:12 INFO - 'install', 19:41:12 INFO - 'run-tests'), 19:41:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:41:12 INFO - 'download_minidump_stackwalk': True, 19:41:12 INFO - 'download_symbols': 'ondemand', 19:41:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:41:12 INFO - 'tooltool.py': '/tools/tooltool.py', 19:41:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:41:12 INFO - '/tools/misc-python/virtualenv.py')}, 19:41:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:41:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:41:12 INFO - 'installer_path': 'installer.exe', 19:41:12 INFO - 'log_level': 'info', 19:41:12 INFO - 'log_name': 'talos', 19:41:12 INFO - 'log_to_console': True, 19:41:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:41:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:41:12 INFO - 'opt_config_files': (), 19:41:12 INFO - 'pip_index': False, 19:41:12 INFO - 'sps_profile': False, 19:41:12 INFO - 'sps_profile_interval': 0, 19:41:12 INFO - 'suite': 'dromaeojs', 19:41:12 INFO - 'system_bits': '32', 19:41:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:41:12 INFO - 'title': 'talos-linux64-ix-061', 19:41:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:41:12 INFO - 'use_talos_json': True, 19:41:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:41:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:41:12 INFO - 'work_dir': 'build'} 19:41:12 INFO - [mozharness: 2016-09-09 02:41:12.010223Z] Running clobber step. 19:41:12 INFO - Running pre-action listener: _resource_record_pre_action 19:41:12 INFO - Running main action method: clobber 19:41:12 INFO - rmtree: /builds/slave/test/build 19:41:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:41:12 INFO - Running post-action listener: _resource_record_post_action 19:41:12 INFO - [mozharness: 2016-09-09 02:41:12.462819Z] Finished clobber step (success) 19:41:12 INFO - [mozharness: 2016-09-09 02:41:12.462921Z] Running read-buildbot-config step. 19:41:12 INFO - Running pre-action listener: _resource_record_pre_action 19:41:12 INFO - Running main action method: read_buildbot_config 19:41:12 INFO - Using buildbot properties: 19:41:12 INFO - { 19:41:12 INFO - "project": "", 19:41:12 INFO - "product": "firefox", 19:41:12 INFO - "script_repo_revision": "production", 19:41:12 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:41:12 INFO - "repository": "", 19:41:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:41:12 INFO - "buildid": "20160908165620", 19:41:12 INFO - "pgo_build": "True", 19:41:12 INFO - "basedir": "/builds/slave/test", 19:41:12 INFO - "buildnumber": 742, 19:41:12 INFO - "slavename": "talos-linux64-ix-061", 19:41:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:41:12 INFO - "platform": "ubuntu64_hw", 19:41:12 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:41:12 INFO - "branch": "mozilla-aurora", 19:41:12 INFO - "repo_path": "releases/mozilla-aurora", 19:41:12 INFO - "revision": "7a8b1ac451d942a5faa6f3a9dfa0c3c55eeed155", 19:41:12 INFO - "stage_platform": "linux64-pgo", 19:41:12 INFO - "builduid": "4f68f85ea8664afc83719d884046bc56", 19:41:12 INFO - "slavebuilddir": "test" 19:41:12 INFO - } 19:41:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 19:41:12 INFO - Running post-action listener: _resource_record_post_action 19:41:12 INFO - [mozharness: 2016-09-09 02:41:12.465741Z] Finished read-buildbot-config step (success) 19:41:12 INFO - [mozharness: 2016-09-09 02:41:12.465828Z] Running download-and-extract step. 19:41:12 INFO - Running pre-action listener: _resource_record_pre_action 19:41:12 INFO - Running main action method: download_and_extract 19:41:12 INFO - mkdir: /builds/slave/test/build/tests 19:41: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')]} 19:41:12 INFO - https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:41:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:41:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:41:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:41:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:41:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:41:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:41:12 INFO - retry: Failed, sleeping 30 seconds before retrying 19:41:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:41:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:41:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:41:42 INFO - retry: Failed, sleeping 60 seconds before retrying 19:42:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:42:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:42:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:42:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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! 19:42:42 INFO - Caught exception: HTTP Error 404: Not Found 19:42:42 INFO - Caught exception: HTTP Error 404: Not Found 19:42:42 INFO - Caught exception: HTTP Error 404: Not Found 19:42:42 INFO - trying https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:42:42 INFO - Downloading https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:42:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:42:43 INFO - Downloaded 1361 bytes. 19:42:43 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:42:43 INFO - Using the following test package requirements: 19:42:43 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:42:43 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:42:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:42:43 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:42:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:42:43 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:42:43 INFO - u'jsshell-linux-x86_64.zip'], 19:42:43 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:42:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:42:43 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:42:43 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:42:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:42:43 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:42:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:42:43 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:42:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:42:43 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:42:43 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:42:43 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:42: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')]} 19:42:43 INFO - https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:42:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:42:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:42:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:42:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:42:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:42:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:42:43 INFO - retry: Failed, sleeping 30 seconds before retrying 19:43:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:43:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:43:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:43:13 INFO - retry: Failed, sleeping 60 seconds before retrying 19:44:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:44:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:44:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:44:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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! 19:44:13 INFO - Caught exception: HTTP Error 404: Not Found 19:44:13 INFO - Caught exception: HTTP Error 404: Not Found 19:44:13 INFO - Caught exception: HTTP Error 404: Not Found 19:44:13 INFO - trying https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:44:13 INFO - Downloading https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:44:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:44:14 INFO - Downloaded 21770896 bytes. 19:44: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'] 19:44: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 19:44:15 INFO - Return code: 0 19:44: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 19:44: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')]} 19:44:15 INFO - https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:44:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:44:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:44:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:44:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:44:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:44:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:44:15 INFO - retry: Failed, sleeping 30 seconds before retrying 19:44:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:44:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:44:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:44:45 INFO - retry: Failed, sleeping 60 seconds before retrying 19:45:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:45:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:45:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:45:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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! 19:45:45 INFO - Caught exception: HTTP Error 404: Not Found 19:45:45 INFO - Caught exception: HTTP Error 404: Not Found 19:45:45 INFO - Caught exception: HTTP Error 404: Not Found 19:45:45 INFO - trying https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:45:45 INFO - Downloading https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:45:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:45:46 INFO - Downloaded 21770896 bytes. 19:45: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'] 19:45: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 19:45:47 INFO - Return code: 0 19:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:45:47 INFO - https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:45:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:45:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:45:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:45:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:45:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:45:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:45:47 INFO - retry: Failed, sleeping 30 seconds before retrying 19:46:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:46:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:46:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:46:17 INFO - retry: Failed, sleeping 60 seconds before retrying 19:47:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:47:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:47:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:47:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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! 19:47:17 INFO - Caught exception: HTTP Error 404: Not Found 19:47:17 INFO - Caught exception: HTTP Error 404: Not Found 19:47:17 INFO - Caught exception: HTTP Error 404: Not Found 19:47:17 INFO - trying https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:47:17 INFO - Downloading https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:47:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:47:19 INFO - Downloaded 14413740 bytes. 19:47: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'] 19:47: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 19:47:19 INFO - Return code: 0 19:47:19 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')]} 19:47:19 INFO - https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:47:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:47:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:47:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:47:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:47:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:47:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:47:19 INFO - retry: Failed, sleeping 30 seconds before retrying 19:47:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:47:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:47:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:47:49 INFO - retry: Failed, sleeping 60 seconds before retrying 19:48:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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 19:48:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:48:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:48:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MOlw47niR0SptCGlNlORgg/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! 19:48:49 INFO - Caught exception: HTTP Error 404: Not Found 19:48:49 INFO - Caught exception: HTTP Error 404: Not Found 19:48:49 INFO - Caught exception: HTTP Error 404: Not Found 19:48:49 INFO - trying https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:48:49 INFO - Downloading https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:48:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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 19:48:51 INFO - Downloaded 63925708 bytes. 19:48:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:48:51 INFO - mkdir: /builds/slave/test/properties 19:48:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:48:51 INFO - Writing to file /builds/slave/test/properties/build_url 19:48:51 INFO - Contents: 19:48:51 INFO - build_url:https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:48:51 INFO - Running post-action listener: _resource_record_post_action 19:48:51 INFO - Running post-action listener: set_extra_try_arguments 19:48:51 INFO - [mozharness: 2016-09-09 02:48:51.880208Z] Finished download-and-extract step (success) 19:48:51 INFO - [mozharness: 2016-09-09 02:48:51.880443Z] Running populate-webroot step. 19:48:51 INFO - Running pre-action listener: _resource_record_pre_action 19:48:51 INFO - Running main action method: populate_webroot 19:48:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:48:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:48:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:48:51 INFO - '--tppagecycles', 19:48:51 INFO - '7'], 19:48:51 INFO - 'tests': ['tsvgm']}}, 19:48:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:48:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:48:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:48:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:48:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:51 INFO - 'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:48:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:48:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:51 INFO - 'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['damp', 'tps']}, 19:48:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:51 INFO - 'tests': ['damp', 'tps']}, 19:48:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['dromaeo_dom']}, 19:48:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:48:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['basic_compositor_video']}, 19:48:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:48:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['a11yr', 19:48:51 INFO - 'ts_paint', 19:48:51 INFO - 'tpaint', 19:48:51 INFO - 'sessionrestore', 19:48:51 INFO - 'sessionrestore_no_auto_restore', 19:48:51 INFO - 'tabpaint']}, 19:48:51 INFO - 'other-e10s': {'tests': ['a11yr', 19:48:51 INFO - 'ts_paint', 19:48:51 INFO - 'tpaint', 19:48:51 INFO - 'sessionrestore', 19:48:51 INFO - 'sessionrestore_no_auto_restore', 19:48:51 INFO - 'tabpaint']}, 19:48:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:48:51 INFO - 'ts_paint', 19:48:51 INFO - 'tpaint', 19:48:51 INFO - 'sessionrestore', 19:48:51 INFO - 'sessionrestore_no_auto_restore', 19:48:51 INFO - 'tabpaint']}, 19:48:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:48:51 INFO - 'ts_paint', 19:48:51 INFO - 'tpaint', 19:48:51 INFO - 'sessionrestore', 19:48:51 INFO - 'sessionrestore_no_auto_restore', 19:48:51 INFO - 'tabpaint']}, 19:48:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['a11yr', 19:48:51 INFO - 'ts_paint', 19:48:51 INFO - 'tpaint', 19:48:51 INFO - 'sessionrestore', 19:48:51 INFO - 'sessionrestore_no_auto_restore', 19:48:51 INFO - 'tabpaint']}, 19:48:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['a11yr', 19:48:51 INFO - 'ts_paint', 19:48:51 INFO - 'tpaint', 19:48:51 INFO - 'sessionrestore', 19:48:51 INFO - 'sessionrestore_no_auto_restore', 19:48:51 INFO - 'tabpaint']}, 19:48:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['tsvgx', 19:48:51 INFO - 'tsvgr_opacity', 19:48:51 INFO - 'tart', 19:48:51 INFO - 'tscrollx', 19:48:51 INFO - 'cart']}, 19:48:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:48:51 INFO - 'tsvgr_opacity', 19:48:51 INFO - 'tart', 19:48:51 INFO - 'tscrollx', 19:48:51 INFO - 'cart']}, 19:48:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:51 INFO - 'talos_options': ['--disable-e10s'], 19:48:51 INFO - 'tests': ['tp5o']}, 19:48:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:51 INFO - 'tests': ['tp5o']}, 19:48:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:51 INFO - 'talos_options': ['--disable-e10s', 19:48:51 INFO - '--xperf_path', 19:48:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:48:51 INFO - 'tests': ['tp5n']}, 19:48:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:48:51 INFO - 'talos_options': ['--xperf_path', 19:48:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:48:51 INFO - 'tests': ['tp5n']}}, 19:48:51 INFO - 'talos.zip': {'path': '', 19:48:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:48:51 INFO - Running post-action listener: _resource_record_post_action 19:48:51 INFO - [mozharness: 2016-09-09 02:48:51.896814Z] Finished populate-webroot step (success) 19:48:51 INFO - [mozharness: 2016-09-09 02:48:51.896903Z] Running create-virtualenv step. 19:48:51 INFO - Running pre-action listener: _resource_record_pre_action 19:48:51 INFO - Running main action method: create_virtualenv 19:48:51 INFO - Creating virtualenv /builds/slave/test/build/venv 19:48:51 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 19:48:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:48:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:48:51 INFO - Using real prefix '/usr' 19:48:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:48:52 INFO - Installing distribute.............................................................................................................................................................................................done. 19:48:54 INFO - Installing pip.................done. 19:48:54 INFO - Return code: 0 19:48:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:48:54 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')]} 19:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:54 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')]} 19:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:54 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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:48:54 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 19:48:54 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 19:48:54 INFO - Using env: {'DISPLAY': ':0', 19:48:54 INFO - 'HOME': '/home/cltbld', 19:48:54 INFO - 'LANG': 'en_US.UTF-8', 19:48:54 INFO - 'LANGUAGE': 'en_US:en', 19:48:54 INFO - 'LOGNAME': 'cltbld', 19:48:54 INFO - 'MAIL': '/var/mail/cltbld', 19:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:54 INFO - 'MOZ_NO_REMOTE': '1', 19:48:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:48:54 INFO - 'NO_EM_RESTART': '1', 19:48:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:48:54 INFO - 'PWD': '/builds/slave/test', 19:48:54 INFO - 'SHELL': '/bin/bash', 19:48:54 INFO - 'SHLVL': '1', 19:48:54 INFO - 'TERM': 'linux', 19:48:54 INFO - 'TMOUT': '86400', 19:48:54 INFO - 'USER': 'cltbld', 19:48:54 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:54 INFO - '_': '/tools/buildbot/bin/python'} 19:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:54 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 19:48:54 INFO - Cleaning up... 19:48:54 INFO - Return code: 0 19:48:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:48:54 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')]} 19:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:54 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')]} 19:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:54 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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:48:54 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 19:48:54 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 19:48:54 INFO - Using env: {'DISPLAY': ':0', 19:48:54 INFO - 'HOME': '/home/cltbld', 19:48:54 INFO - 'LANG': 'en_US.UTF-8', 19:48:54 INFO - 'LANGUAGE': 'en_US:en', 19:48:54 INFO - 'LOGNAME': 'cltbld', 19:48:54 INFO - 'MAIL': '/var/mail/cltbld', 19:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:54 INFO - 'MOZ_NO_REMOTE': '1', 19:48:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:48:54 INFO - 'NO_EM_RESTART': '1', 19:48:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:48:54 INFO - 'PWD': '/builds/slave/test', 19:48:54 INFO - 'SHELL': '/bin/bash', 19:48:54 INFO - 'SHLVL': '1', 19:48:54 INFO - 'TERM': 'linux', 19:48:54 INFO - 'TMOUT': '86400', 19:48:54 INFO - 'USER': 'cltbld', 19:48:54 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:54 INFO - '_': '/tools/buildbot/bin/python'} 19:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:54 INFO - Downloading/unpacking psutil>=3.1.1 19:48:54 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 19:48:54 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 19:48:54 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 19:48:54 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 19:48:54 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 19:48:54 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 19:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:48:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:48:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:48:56 INFO - Installing collected packages: psutil 19:48:56 INFO - Running setup.py install for psutil 19:48:56 INFO - building 'psutil._psutil_linux' extension 19:48:56 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 19:48: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 19:48:57 INFO - building 'psutil._psutil_posix' extension 19:48: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 19:48: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:48:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:48:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:48:57 INFO - Successfully installed psutil 19:48:57 INFO - Cleaning up... 19:48:57 INFO - Return code: 0 19:48:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:48:57 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')]} 19:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:57 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')]} 19:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:57 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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:48:57 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 19:48:57 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 19:48:57 INFO - Using env: {'DISPLAY': ':0', 19:48:57 INFO - 'HOME': '/home/cltbld', 19:48:57 INFO - 'LANG': 'en_US.UTF-8', 19:48:57 INFO - 'LANGUAGE': 'en_US:en', 19:48:57 INFO - 'LOGNAME': 'cltbld', 19:48:57 INFO - 'MAIL': '/var/mail/cltbld', 19:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:57 INFO - 'MOZ_NO_REMOTE': '1', 19:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:48:57 INFO - 'NO_EM_RESTART': '1', 19:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:48:57 INFO - 'PWD': '/builds/slave/test', 19:48:57 INFO - 'SHELL': '/bin/bash', 19:48:57 INFO - 'SHLVL': '1', 19:48:57 INFO - 'TERM': 'linux', 19:48:57 INFO - 'TMOUT': '86400', 19:48:57 INFO - 'USER': 'cltbld', 19:48:57 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:57 INFO - '_': '/tools/buildbot/bin/python'} 19:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:48:57 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 19:48:57 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 19:48:57 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 19:48:57 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 19:48:57 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 19:48:57 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 19:48:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:48:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:48:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:48:59 INFO - Installing collected packages: mozsystemmonitor 19:48:59 INFO - Running setup.py install for mozsystemmonitor 19:48:59 INFO - Successfully installed mozsystemmonitor 19:48:59 INFO - Cleaning up... 19:48:59 INFO - Return code: 0 19:48:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:48:59 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')]} 19:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:48:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:59 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')]} 19:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:48:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:48:59 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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:48:59 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 19:48:59 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 19:48:59 INFO - Using env: {'DISPLAY': ':0', 19:48:59 INFO - 'HOME': '/home/cltbld', 19:48:59 INFO - 'LANG': 'en_US.UTF-8', 19:48:59 INFO - 'LANGUAGE': 'en_US:en', 19:48:59 INFO - 'LOGNAME': 'cltbld', 19:48:59 INFO - 'MAIL': '/var/mail/cltbld', 19:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:59 INFO - 'MOZ_NO_REMOTE': '1', 19:48:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:48:59 INFO - 'NO_EM_RESTART': '1', 19:48:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:48:59 INFO - 'PWD': '/builds/slave/test', 19:48:59 INFO - 'SHELL': '/bin/bash', 19:48:59 INFO - 'SHLVL': '1', 19:48:59 INFO - 'TERM': 'linux', 19:48:59 INFO - 'TMOUT': '86400', 19:48:59 INFO - 'USER': 'cltbld', 19:48:59 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:59 INFO - '_': '/tools/buildbot/bin/python'} 19:48:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:48:59 INFO - Downloading/unpacking jsonschema==2.5.1 19:48:59 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 19:48:59 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 19:48:59 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 19:48:59 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 19:48:59 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 19:48:59 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 19:49:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:49:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:49:01 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 19:49:01 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 19:49:01 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 19:49:01 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 19:49:01 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 19:49:01 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 19:49:01 INFO - Downloading functools32-3.2.3-2.tar.gz 19:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:49:02 INFO - warning: no files found matching '*.txt' 19:49:02 INFO - no previously-included directories found matching 'build' 19:49:02 INFO - no previously-included directories found matching 'dist' 19:49:02 INFO - no previously-included directories found matching '.git*' 19:49:02 INFO - Installing collected packages: jsonschema, functools32 19:49:02 INFO - Running setup.py install for functools32 19:49:02 INFO - warning: no files found matching '*.txt' 19:49:02 INFO - no previously-included directories found matching 'build' 19:49:02 INFO - no previously-included directories found matching 'dist' 19:49:02 INFO - no previously-included directories found matching '.git*' 19:49:02 INFO - Successfully installed jsonschema functools32 19:49:02 INFO - Cleaning up... 19:49:02 INFO - Return code: 0 19:49:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:49:02 INFO - Using env: {'DISPLAY': ':0', 19:49:02 INFO - 'HOME': '/home/cltbld', 19:49:02 INFO - 'LANG': 'en_US.UTF-8', 19:49:02 INFO - 'LANGUAGE': 'en_US:en', 19:49:02 INFO - 'LOGNAME': 'cltbld', 19:49:02 INFO - 'MAIL': '/var/mail/cltbld', 19:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:02 INFO - 'MOZ_NO_REMOTE': '1', 19:49:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:02 INFO - 'NO_EM_RESTART': '1', 19:49:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:02 INFO - 'PWD': '/builds/slave/test', 19:49:02 INFO - 'SHELL': '/bin/bash', 19:49:02 INFO - 'SHLVL': '1', 19:49:02 INFO - 'TERM': 'linux', 19:49:02 INFO - 'TMOUT': '86400', 19:49:02 INFO - 'USER': 'cltbld', 19:49:02 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:02 INFO - '_': '/tools/buildbot/bin/python'} 19:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:49:02 INFO - Cleaning up... 19:49:02 INFO - Return code: 0 19:49:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:49:02 INFO - Using env: {'DISPLAY': ':0', 19:49:02 INFO - 'HOME': '/home/cltbld', 19:49:02 INFO - 'LANG': 'en_US.UTF-8', 19:49:02 INFO - 'LANGUAGE': 'en_US:en', 19:49:02 INFO - 'LOGNAME': 'cltbld', 19:49:02 INFO - 'MAIL': '/var/mail/cltbld', 19:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:02 INFO - 'MOZ_NO_REMOTE': '1', 19:49:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:02 INFO - 'NO_EM_RESTART': '1', 19:49:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:02 INFO - 'PWD': '/builds/slave/test', 19:49:02 INFO - 'SHELL': '/bin/bash', 19:49:02 INFO - 'SHLVL': '1', 19:49:02 INFO - 'TERM': 'linux', 19:49:02 INFO - 'TMOUT': '86400', 19:49:02 INFO - 'USER': 'cltbld', 19:49:02 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:02 INFO - '_': '/tools/buildbot/bin/python'} 19:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:02 INFO - Downloading/unpacking blobuploader==1.2.4 19:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:04 INFO - Downloading blobuploader-1.2.4.tar.gz 19:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:49:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:49:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:05 INFO - Downloading docopt-0.6.1.tar.gz 19:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:49:05 INFO - Installing collected packages: blobuploader, requests, docopt 19:49:05 INFO - Running setup.py install for blobuploader 19:49:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:49:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:49:05 INFO - Running setup.py install for requests 19:49:05 INFO - Running setup.py install for docopt 19:49:05 INFO - Successfully installed blobuploader requests docopt 19:49:05 INFO - Cleaning up... 19:49:05 INFO - Return code: 0 19:49:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:05 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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:49:05 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 19:49:05 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 19:49:05 INFO - Using env: {'DISPLAY': ':0', 19:49:05 INFO - 'HOME': '/home/cltbld', 19:49:05 INFO - 'LANG': 'en_US.UTF-8', 19:49:05 INFO - 'LANGUAGE': 'en_US:en', 19:49:05 INFO - 'LOGNAME': 'cltbld', 19:49:05 INFO - 'MAIL': '/var/mail/cltbld', 19:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:05 INFO - 'MOZ_NO_REMOTE': '1', 19:49:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:05 INFO - 'NO_EM_RESTART': '1', 19:49:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:05 INFO - 'PWD': '/builds/slave/test', 19:49:05 INFO - 'SHELL': '/bin/bash', 19:49:05 INFO - 'SHLVL': '1', 19:49:05 INFO - 'TERM': 'linux', 19:49:05 INFO - 'TMOUT': '86400', 19:49:05 INFO - 'USER': 'cltbld', 19:49:05 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:05 INFO - '_': '/tools/buildbot/bin/python'} 19:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:49:06 INFO - Running setup.py (path:/tmp/pip-8dgF1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:49:06 INFO - Running setup.py (path:/tmp/pip-YtDp2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:49:06 INFO - Running setup.py (path:/tmp/pip-_rDkV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:49:06 INFO - Running setup.py (path:/tmp/pip-JON4e8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:49:06 INFO - Running setup.py (path:/tmp/pip-co8irL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:49:06 INFO - Running setup.py (path:/tmp/pip-YCkBW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:49:06 INFO - Running setup.py (path:/tmp/pip-XTLaNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:49:06 INFO - Running setup.py (path:/tmp/pip-Mvh108-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:49:06 INFO - Running setup.py (path:/tmp/pip-UCNz06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:49:06 INFO - Running setup.py (path:/tmp/pip-wR4AyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:49:06 INFO - Running setup.py (path:/tmp/pip-I9Fkrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:49:06 INFO - Running setup.py (path:/tmp/pip-oss39q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:49:07 INFO - Running setup.py (path:/tmp/pip-AVBbU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:49:07 INFO - Running setup.py (path:/tmp/pip-jz3lr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:07 INFO - Running setup.py (path:/tmp/pip-i6xxX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:49:07 INFO - Running setup.py (path:/tmp/pip-OgE8GG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:49:07 INFO - Running setup.py (path:/tmp/pip-2hQQXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:49:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:49:07 INFO - Running setup.py install for manifestparser 19:49:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:49:07 INFO - Running setup.py install for mozcrash 19:49:07 INFO - Running setup.py install for mozdebug 19:49:07 INFO - Running setup.py install for mozdevice 19:49:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:49:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:49:07 INFO - Running setup.py install for mozfile 19:49:07 INFO - Running setup.py install for mozhttpd 19:49:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Running setup.py install for mozinfo 19:49:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Running setup.py install for mozInstall 19:49:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Running setup.py install for mozleak 19:49:08 INFO - Running setup.py install for mozlog 19:49:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Running setup.py install for moznetwork 19:49:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Running setup.py install for mozprocess 19:49:08 INFO - Running setup.py install for mozprofile 19:49:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Running setup.py install for mozrunner 19:49:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:49:08 INFO - Running setup.py install for mozscreenshot 19:49:08 INFO - Running setup.py install for moztest 19:49:09 INFO - Running setup.py install for mozversion 19:49:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:49:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:49:09 INFO - Cleaning up... 19:49:09 INFO - Return code: 0 19:49:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:09 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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:49:09 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 19:49:09 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 19:49:09 INFO - Using env: {'DISPLAY': ':0', 19:49:09 INFO - 'HOME': '/home/cltbld', 19:49:09 INFO - 'LANG': 'en_US.UTF-8', 19:49:09 INFO - 'LANGUAGE': 'en_US:en', 19:49:09 INFO - 'LOGNAME': 'cltbld', 19:49:09 INFO - 'MAIL': '/var/mail/cltbld', 19:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:09 INFO - 'MOZ_NO_REMOTE': '1', 19:49:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:09 INFO - 'NO_EM_RESTART': '1', 19:49:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:09 INFO - 'PWD': '/builds/slave/test', 19:49:09 INFO - 'SHELL': '/bin/bash', 19:49:09 INFO - 'SHLVL': '1', 19:49:09 INFO - 'TERM': 'linux', 19:49:09 INFO - 'TMOUT': '86400', 19:49:09 INFO - 'USER': 'cltbld', 19:49:09 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:09 INFO - '_': '/tools/buildbot/bin/python'} 19:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:49:09 INFO - Running setup.py (path:/tmp/pip-v5KaHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:49:09 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)) 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:49:09 INFO - Running setup.py (path:/tmp/pip-F0U4E5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:49:09 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)) 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:49:09 INFO - Running setup.py (path:/tmp/pip-_O1wA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:49:09 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)) 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:49:09 INFO - Running setup.py (path:/tmp/pip-j6yMhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:49:09 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)) 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:49:09 INFO - Running setup.py (path:/tmp/pip-n5QWm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:49:09 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)) 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:49:09 INFO - Running setup.py (path:/tmp/pip-QApTUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:49:09 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)) 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:49:09 INFO - Running setup.py (path:/tmp/pip-grfnkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:49:09 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)) 19:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:49:09 INFO - Running setup.py (path:/tmp/pip-wkmTOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:49: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)) 19:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:49:10 INFO - Running setup.py (path:/tmp/pip-mr8vxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:49: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)) 19:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:49:10 INFO - Running setup.py (path:/tmp/pip-eXNa05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:49:10 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)) 19:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:49:10 INFO - Running setup.py (path:/tmp/pip-xC8Bh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:49:10 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)) 19:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:49:10 INFO - Running setup.py (path:/tmp/pip-rV2aGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:49:10 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)) 19:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:49:10 INFO - Running setup.py (path:/tmp/pip-hVPVhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:49:10 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)) 19:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:49:10 INFO - Running setup.py (path:/tmp/pip-JQnK6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:49:10 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)) 19:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:10 INFO - Running setup.py (path:/tmp/pip-TC7cft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:49:10 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)) 19:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:49:10 INFO - Running setup.py (path:/tmp/pip-9Amczb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:49:10 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)) 19:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:49:10 INFO - Running setup.py (path:/tmp/pip-z9P4MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:49:10 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)) 19:49:10 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)) 19:49:10 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)) 19:49:10 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)) 19:49:10 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)) 19:49:10 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)) 19:49:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:49:12 INFO - Downloading blessings-1.6.tar.gz 19:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:49:12 INFO - Installing collected packages: blessings 19:49:12 INFO - Running setup.py install for blessings 19:49:12 INFO - Successfully installed blessings 19:49:12 INFO - Cleaning up... 19:49:12 INFO - Return code: 0 19:49:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:49:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:49:12 INFO - Reading from file tmpfile_stdout 19:49:12 INFO - Current package versions: 19:49:12 INFO - argparse == 1.2.1 19:49:12 INFO - blessings == 1.6 19:49:12 INFO - blobuploader == 1.2.4 19:49:12 INFO - docopt == 0.6.1 19:49:12 INFO - functools32 == 3.2.3-2 19:49:12 INFO - jsonschema == 2.5.1 19:49:12 INFO - manifestparser == 1.1 19:49:12 INFO - mozInstall == 1.12 19:49:12 INFO - mozcrash == 0.17 19:49:12 INFO - mozdebug == 0.1 19:49:12 INFO - mozdevice == 0.48 19:49:12 INFO - mozfile == 1.2 19:49:12 INFO - mozhttpd == 0.7 19:49:12 INFO - mozinfo == 0.9 19:49:12 INFO - mozleak == 0.1 19:49:12 INFO - mozlog == 3.3 19:49:12 INFO - moznetwork == 0.27 19:49:12 INFO - mozprocess == 0.23 19:49:12 INFO - mozprofile == 0.28 19:49:12 INFO - mozrunner == 6.12 19:49:12 INFO - mozscreenshot == 0.1 19:49:12 INFO - mozsystemmonitor == 0.3 19:49:12 INFO - moztest == 0.7 19:49:12 INFO - mozversion == 1.4 19:49:12 INFO - psutil == 3.1.1 19:49:12 INFO - requests == 1.2.3 19:49:12 INFO - wsgiref == 0.1.2 19:49:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:49: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 19:49: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 19:49:13 INFO - Using env: {'DISPLAY': ':0', 19:49:13 INFO - 'HOME': '/home/cltbld', 19:49:13 INFO - 'LANG': 'en_US.UTF-8', 19:49:13 INFO - 'LANGUAGE': 'en_US:en', 19:49:13 INFO - 'LOGNAME': 'cltbld', 19:49:13 INFO - 'MAIL': '/var/mail/cltbld', 19:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:13 INFO - 'MOZ_NO_REMOTE': '1', 19:49:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:13 INFO - 'NO_EM_RESTART': '1', 19:49:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:13 INFO - 'PWD': '/builds/slave/test', 19:49:13 INFO - 'SHELL': '/bin/bash', 19:49:13 INFO - 'SHLVL': '1', 19:49:13 INFO - 'TERM': 'linux', 19:49:13 INFO - 'TMOUT': '86400', 19:49:13 INFO - 'USER': 'cltbld', 19:49:13 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:13 INFO - '_': '/tools/buildbot/bin/python'} 19:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:13 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)) 19:49:13 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)) 19:49:13 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)) 19:49:13 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)) 19:49:13 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)) 19:49:13 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)) 19:49:13 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)) 19:49:13 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)) 19:49:13 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)) 19:49:13 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)) 19:49:13 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)) 19:49:13 INFO - Cleaning up... 19:49:13 INFO - Return code: 0 19:49:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:49: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')]} 19:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49: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')]} 19:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:49:13 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 0x12a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1568180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', '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 19:49:13 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 19:49:13 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 19:49:13 INFO - Using env: {'DISPLAY': ':0', 19:49:13 INFO - 'HOME': '/home/cltbld', 19:49:13 INFO - 'LANG': 'en_US.UTF-8', 19:49:13 INFO - 'LANGUAGE': 'en_US:en', 19:49:13 INFO - 'LOGNAME': 'cltbld', 19:49:13 INFO - 'MAIL': '/var/mail/cltbld', 19:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:13 INFO - 'MOZ_NO_REMOTE': '1', 19:49:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:13 INFO - 'NO_EM_RESTART': '1', 19:49:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:13 INFO - 'PWD': '/builds/slave/test', 19:49:13 INFO - 'SHELL': '/bin/bash', 19:49:13 INFO - 'SHLVL': '1', 19:49:13 INFO - 'TERM': 'linux', 19:49:13 INFO - 'TMOUT': '86400', 19:49:13 INFO - 'USER': 'cltbld', 19:49:13 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:13 INFO - '_': '/tools/buildbot/bin/python'} 19:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:49:13 INFO - Cleaning up... 19:49:13 INFO - Return code: 0 19:49:13 INFO - Running post-action listener: _resource_record_post_action 19:49:13 INFO - Running post-action listener: _start_resource_monitoring 19:49:13 INFO - Starting resource monitoring. 19:49:13 INFO - [mozharness: 2016-09-09 02:49:13.476914Z] Finished create-virtualenv step (success) 19:49:13 INFO - [mozharness: 2016-09-09 02:49:13.477470Z] Running install step. 19:49:13 INFO - Running pre-action listener: _resource_record_pre_action 19:49:13 INFO - Running main action method: install 19:49:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:49:13 INFO - Reading from file tmpfile_stdout 19:49:13 INFO - Detecting whether we're running mozinstall >=1.0... 19:49:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:49:13 INFO - Reading from file tmpfile_stdout 19:49:13 INFO - Output received: 19:49:13 INFO - Usage: mozinstall [options] installer 19:49:13 INFO - Options: 19:49:13 INFO - -h, --help show this help message and exit 19:49:13 INFO - -d DEST, --destination=DEST 19:49:13 INFO - Directory to install application into. [default: 19:49:13 INFO - "/builds/slave/test"] 19:49:13 INFO - --app=APP Application being installed. [default: firefox] 19:49:13 INFO - mkdir: /builds/slave/test/build/application 19:49:13 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'] 19:49:13 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 19:49:30 INFO - Reading from file tmpfile_stdout 19:49:30 INFO - Output received: 19:49:30 INFO - /builds/slave/test/build/application/firefox/firefox 19:49:30 INFO - Running post-action listener: _resource_record_post_action 19:49:30 INFO - [mozharness: 2016-09-09 02:49:30.326056Z] Finished install step (success) 19:49:30 INFO - [mozharness: 2016-09-09 02:49:30.326335Z] Running run-tests step. 19:49:30 INFO - Running pre-action listener: _resource_record_pre_action 19:49:30 INFO - Running main action method: run_tests 19:49:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:49:30 INFO - Python 2.7.3 19:49:30 INFO - Return code: 0 19:49:30 INFO - grabbing minidump binary from tooltool 19:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:49:30 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 0x15663b0>, '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 0x15672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1567740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:49:30 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 19:49:30 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 19:49:30 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 19:49:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:49:30 INFO - Return code: 0 19:49:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:49:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:49:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:49:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:49:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:49:30 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/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 19:49:30 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/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --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 19:49:30 INFO - Using env: {'DISPLAY': ':0', 19:49:30 INFO - 'HOME': '/home/cltbld', 19:49:30 INFO - 'LANG': 'en_US.UTF-8', 19:49:30 INFO - 'LANGUAGE': 'en_US:en', 19:49:30 INFO - 'LOGNAME': 'cltbld', 19:49:30 INFO - 'MAIL': '/var/mail/cltbld', 19:49:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:49:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:49:30 INFO - 'MOZ_NO_REMOTE': '1', 19:49:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:49:30 INFO - 'NO_EM_RESTART': '1', 19:49:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:49:30 INFO - 'PWD': '/builds/slave/test', 19:49:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:49:30 INFO - 'SHELL': '/bin/bash', 19:49:30 INFO - 'SHLVL': '1', 19:49:30 INFO - 'TERM': 'linux', 19:49:30 INFO - 'TMOUT': '86400', 19:49:30 INFO - 'USER': 'cltbld', 19:49:30 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303', 19:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:49:30 INFO - '_': '/tools/buildbot/bin/python'} 19:49:30 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/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 19:49:30 INFO - mozversion application_buildid: 20160908165620 19:49:30 INFO - mozversion application_changeset: 7a8b1ac451d942a5faa6f3a9dfa0c3c55eeed155 19:49:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:49:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:49:30 INFO - mozversion application_name: Firefox 19:49:30 INFO - mozversion application_remotingname: firefox-dev 19:49:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:49:30 INFO - mozversion application_vendor: Mozilla 19:49:30 INFO - mozversion application_version: 50.0a2 19:49:30 INFO - mozversion platform_buildid: 20160908165620 19:49:30 INFO - mozversion platform_changeset: 7a8b1ac451d942a5faa6f3a9dfa0c3c55eeed155 19:49:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:49:30 INFO - mozversion platform_version: 50.0a2 19:49:30 INFO - using testdate: 1473389370 19:49:30 INFO - actual date: 1473389370 19:49:30 INFO - starting webserver on 'localhost:35035' 19:49:30 INFO - SUITE-START | Running 2 tests 19:49:30 INFO - TEST-START | dromaeo_css 19:49:30 INFO - operating with platform_type : linux_ 19:49:30 INFO - Initialising browser for dromaeo_css test... 19:49:30 INFO - TEST-INFO | started process 27734 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw2sq2W/profile http://localhost:35035/getInfo.html) 19:49:35 INFO - PROCESS | 27734 | __metrics Screen width/height:1600/1200 19:49:35 INFO - PROCESS | 27734 | colorDepth:24 19:49:35 INFO - PROCESS | 27734 | Browser inner width/height: 1024/697 19:49:35 INFO - PROCESS | 27734 | __metrics 19:49:35 INFO - PROCESS | 27734 | 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" 19:49:35 INFO - PROCESS | 27734 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:49:36 INFO - TEST-INFO | 27734: exit 0 19:49:36 INFO - Browser initialized. 19:49:36 INFO - Running cycle 1/1 for dromaeo_css test... 19:49:36 INFO - TEST-INFO | started process 27825 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw2sq2W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:49:48 INFO - PROCESS | 27825 | RSS: Main: 146079744 19:49:48 INFO - PROCESS | 27825 | 19:50:39 INFO - PROCESS | 27825 | 1473389439438 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] 19:52:48 INFO - PROCESS | 27825 | Cycle 1(1): loaded http://localhost:35035/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35035/tests/dromaeo/cssquery-ext.html) 19:52:49 INFO - PROCESS | 27825 | RSS: Main: 274718720 19:52:49 INFO - PROCESS | 27825 | 19:55:51 INFO - PROCESS | 27825 | Cycle 1(1): loaded http://localhost:35035/tests/dromaeo/cssquery-ext.html (next: http://localhost:35035/tests/dromaeo/cssquery-jquery.html) 19:55:52 INFO - PROCESS | 27825 | RSS: Main: 229044224 19:55:52 INFO - PROCESS | 27825 | 19:58:54 INFO - PROCESS | 27825 | Cycle 1(1): loaded http://localhost:35035/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35035/tests/dromaeo/cssquery-mootools.html) 19:58:54 INFO - PROCESS | 27825 | RSS: Main: 206327808 19:58:54 INFO - PROCESS | 27825 | 20:01:56 INFO - PROCESS | 27825 | Cycle 1(1): loaded http://localhost:35035/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35035/tests/dromaeo/cssquery-prototype.html) 20:01:57 INFO - PROCESS | 27825 | RSS: Main: 226500608 20:01:57 INFO - PROCESS | 27825 | 20:04:59 INFO - PROCESS | 27825 | Cycle 1(1): loaded http://localhost:35035/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35035/tests/dromaeo/cssquery-yui.html) 20:05:00 INFO - PROCESS | 27825 | RSS: Main: 178728960 20:05:00 INFO - PROCESS | 27825 | 20:08:03 INFO - PROCESS | 27825 | Cycle 1(1): loaded http://localhost:35035/tests/dromaeo/cssquery-yui.html (next: http://localhost:35035/tests/dromaeo/cssquery-dojo.html) 20:08:03 INFO - PROCESS | 27825 | RSS: Main: 264028160 20:08:03 INFO - PROCESS | 27825 | 20:08:03 INFO - PROCESS | 27825 | __start_tp_report 20:08:03 INFO - PROCESS | 27825 | _x_x_mozilla_page_load 20:08:03 INFO - PROCESS | 27825 | _x_x_mozilla_page_load_details 20:08:03 INFO - PROCESS | 27825 | |i|pagename|runs| 20:08:03 INFO - PROCESS | 27825 | |0;dojo.html;3160.1467603746987;3218.62985756638;3246.496792997085;3253.6095668519783;3277.3445310937786;5404.648697834638;5521.171824226368;5533.446655334465;5540.529055030327;5542.612017158801;5477.0415177344;5531.1426728857095;5542.224088627595;5542.91685624715;5552.7223638818095;4968.87577810554;5006.899862002758;5048.899022019557;5095.5923526118;5125.359330083739;4942.01159768046;5084.77118529667;5123.871903202413;5127.794888204486;5133.589312854962;4943.1102401567705;4986.700797952134;4989.775460104286;5001.474845141244;5027.170516864713;5080.13637681595;5095.566876815476;5104;5106.4638212987475;5120.487951204868;4920.975395123037;4930.359053323081;4956.702597844141;5003.949960500421;5036.84889453317;5405.351357837085;5412.83761487156;5417.349918009824;5426.864328391783;5457.181422786574;5909.084091965752;5922.526197904157;5924.377940316291;5927.2590926134235;5933.525317974551;5848.912266316009;5894.941050589482;5896.557758168155;5945.970270148665;5953.196318497034;5550.111982082847;5556.333240011225;5566.693831839207;5579.525740312119;5601.047821870292;5873.2658417697785;6048.12302216185;6060.33336333003;6071.939280607137;6073.939260607426;5683.062294721325;5730.484256416942;5765.682887441147;5822.359540450547;5838.4453476919625;5892.027815410409;5892.057270836645;5895.882082358329;5900.468957793733;5906.26171728534;5791.536677065825;5867.882642347129;5872.972229859843;5895.557833162444;5896.528277737771;5796.536277097822;5825.79609713658;5845.152452894306;5897.941020589739;5932.406759324033;4510.2783554631105;4510.616597589242;4513.661475389359;4527.4340707411575;4528.230200865795;3967;4062.1672557125717;4068.1863627274665;4073.3889916512762;4109.609587089221;5440;5611.5230205433;5636.943630563642;5655.745491452894;5722.656640601577;5906.7637294508595;5937.495312898365;5937.52499800015;5943.851403714942;5957.76168953237;5678.943210567924;5691.146328050825;5700.087985922232;5704.3154821421695;5711.371749107595;5013.49865013503;5090.720010399389;5091.1854103343285;5095.031943930641;5195.584353251731;6109.389061093855;6113.052476866004;6113.29697084841;6117.602355846856;6128.754849805958;5911.965406053838;5937.287525496968;5944.494717949023;5945.405459454193;5958.1658567801405;5698.601097923106;5893.970530147321;5954.791582294599;6027;6078.878422431615;5753.568482363756;5766.250387449605;5771.740271687868;5772.855678608068;5772.971135144466;5836.912446313394;5850.444207800422;5875.794347197828;5882.382349853204;5886.205362276038;5668.5465162787705;5728.627639203439;5738.5696072795545;5753.223314852441;5757.222774925499;5757.827265182052;5784.623999440023;5791.218185544897;5798.913016304675;5808.564357673107;5734.2258795062135;5750.30996280449;5755.769769209185;5781.392953739797;5787.594868359174;5625.409332020243;5711.286089238845;5732.684702341411;5761.452661997104;5830.737616807169;5670.886582268414;5691.402402747817;5695.857603559944;5696.857578560569;5723.112917497794 20:08:03 INFO - PROCESS | 27825 | |1;ext.html;582.8979928512509;618.2611778924185;641.9839504012402;654.1920727901042;667.4260136282793;35971.82014089929;45663.54336456634;46637.76681116589;46855.76572117139;50442.49557504424;47004.294935575905;47499;47512.762436187855;47583.52416475834;47614.52385476144;8519.53142577158;9260.768480787983;9453.101955314234;9455.527223638825;10993.780124397506;10842.241043126984;11268.943655281711;11279.605213817518;11280.435978201098;11280.830787538152;11244.269122507052;11271.211015228937;11286.379249141293;11293.4917928693;11298.152638552141;11250.718732031683;11308.830367544493;11311.660650180507;11319.660410187707;11321.716957076056;11019.559217631286;11109.00018998294;11112;11114.277571957879;11116.722081947935;45266.547334526564;45536.77231613854;45550;45625.771871140765;45631.08737825258;3903.8243279052226;3918.8628398006217;3972.8410863565423;4065.715399922007;4236.555161708007;34134.317313653584;34717;34751.30497390063;34811.12972175691;35057.298854022774;4647.279671650899;4654.930176047362;4659.184642687516;4693.78877950493;4702.200625893606;371048;371667;372003;372603;374503;67139.32860671429;67593.66203169052;67609.66195168992;67699.66150169316;67753.66123169352;39979.80010099931;40903.38644920329;40996;42531;69172.65413672899;20021.699674504893;20225.696614550794;20233;20241.696374554394;20253.392398228076;14118.941079419084;15088.698226035638;15398;15443.613909652124;15517.922410387875;21157.25949591788;21260.25589104374;21307.8934605326;21339.67990480144;21365.67951480729;7204.423646108404;7240.818979525554;7278.927210727825;7315.231900650461;7523.623818809037;1003.207466101786;1098.807708650983;1125.5835340923747;1146.850909381775;1179.5576658752968;32746;33021.66978330234;33159.502607460905;33236.83381583125;33814.66185338164;1212.7635111153418;1220.9877901220875;1255.0712472770085;1257.88050135237;1351.959441216765;17819.46541603754;17947;17982.609390130638;17985.1007449627;18104.547386315448;630410;639493;640214;640425.5957440459;642055;62109.37890621126;62244.68877655673;62361;62596.687016564625;62697.37302627006;1361.9863801362058;1367.3231750283474;1381.2196109198471;1423.7081398313321;1457.1475686723174;27072.458550829266;36093.81953090323;36737;39056.609433905294;39424.40863386994;61593.692031539555;62044.689776550826;62159;62359.06461403173;62533;402150;406114;406462;407696;407862;27774.722252777214;30299;30393.24016899595;30714.84642576773;31746;52393.7380313096;53065.20402194047;53543.46456535385;53580;53902.00629171535;43793.9844905644;43948;44230.336544951206;45780.77109614431;45836.31245531253;44845.55154448414;45075.774621126686;45563.772181138884;45817.15109773961;45828.31257531073;45284.320735189656;45731;46594.76702616601;46956;47099.29351059668;35866.64133358737;38278.19068169073;38834.80582597069;39331.80334098311;39715.40426893657;29791;29993.250168745955;30125.427367416607;30169.69830301669;30473.390532188787 20:08:03 INFO - PROCESS | 27825 | |2;jquery.html;420.72652775695815;422.5605370414769;430.68990326964575;430.8233624214073;445.3786967180783;18915.527111822194;20017.19931202752;20406.795932040714;20417.285395011142;20731.792682073174;19360;19580.706289405636;19790.802091979076;19914.70127948078;19992.40022799315;5377.784888604461;5568.331800183983;5572.972135139318;5573.996680597491;5583.776648934037;5508.118701007839;5565.30433695788;5574.58190635704;5953.166556682068;5975.850603734898;5849.385814489481;5889.793857214999;5918.644881307136;5930.140129681194;5934.851628709295;5487;5489.835304940818;5503.862403439906;5526.889462210774;5856.677882716449;5339.145736682151;5381.838544843661;5385.542228910547;5387.218853266255;5392.811251606175;14458.132512049311;14552.92723536386;14675.192864392455;14685.48600798967;14698.779518307234;2874.238326843388;3013.2165636934337;3061.892833750808;3135.984320078408;3189.059227527885;10402.375857448576;10642.840357394567;10743.78512429747;10809.67570972872;11229.831552526719;1970.8718933269292;2061;2066.163203902422;2086.2698055680544;2149.2477632828386;67992.36623235841;75006.24993750102;80132;89752.5512372434;91312.54343728372;9788.510574471247;10262.332948358333;10355.585776568852;11753.647390578297;12091.45588448522;13934.721305574034;13991.440342386393;14068.577942661737;14109.365078571489;14189.574312770634;8821.426607270507;8829.602667879832;8890.73327800167;8908.8663670045;9028;9052.411593246417;9087.363884528151;9159.129882661136;9317.068293170765;9392.765180870396;4363.956360436418;4364.454443194601;4405.559444055569;4424.867253982386;4437.400950871655;2044.6524090904581;2052.3022172461137;2109.525356794709;2110.1981247125996;2129.712488814021;899.0919171636564;996.6561536269975;1013.9036791504795;1056.3028401255287;1148.4315263944402;20061.799382005993;20569.177232910515;20978.895105524374;21022.789772102387;21128.260510882046;1045.9581616735397;1050.9369437833755;1076.923076923077;1097.3690128176331;1111.5776005118003;10906.781864362669;11751.33165542283;11800.645980620595;11817.704557386134;11887.702807429883;500298;504945;506656;507421;511465;89081;91147.5442622796;91198.54400727953;91294;91377;1042.5308611125024;1054.414799786126;1059.4649701900491;1062.574970011986;1066.2269854355686;18136.818631813512;18162;18451.261949521868;18778.342758003404;19016.334428294944;35280.29439411146;41369.586304136574;45699.31451028171;46801.76599116983;46857.7199596025;62942.68528657327;63741.6812915951;64038;64680.676596616715;64940.35059649343;8610.354223433142;8749;8758.91241087581;8887.066857979818;8935.910640893768;10189.490525473548;13040.739185216433;13600.455981761015;13743.793843092562;13902.30488475552;11880.168388212742;12093.153479256367;12110.212836165621;12161.817572736592;12369.93815030955;11482.196246262682;11801.114916381133;11947.641570752892;12126.333051682243;12443.564475243324;12102.697432564257;12184.329861857683;12232.755344892874;12313.56902508408;12549.290073016939;8686.957565092229;8834.823303534022;8857.247133993682;9161.083891610786;9222.123898229658;10978.890211097787;12199.756004879675;12244.571439999558;12308.692282693004;12360.75278494443 20:08:03 INFO - PROCESS | 27825 | |3;mootools.html;1388.9722205555884;1535.976960345595;1567.1772319532245;1743.2155530011498;1889.9433017009485;8548.316134709225;8902.688405905787;8951.507667078316;9241;9256.58345374458;9132.497712625804;9235.39969901956;9247.306452016091;9247.49138797366;9252.907470925305;2350.4241460842077;2370.5377451396985;2372.323887692008;2375.0381095656285;2375.1686909581595;2312.9653055204185;2335.8131349492;2365.9826274701873;2378.5361854438393;2381.285614315707;2354.1760383865594;2368.017272831773;2374.6912901322803;2386.95226095478;2390.40239940015;2288.4050146961745;2304.942376440594;2316.9073237070497;2320.0835669910375;2349.436135327509;2317.723300105035;2324.4305145239355;2343.0393538649073;2345.202631105415;2348.2602980061397;9074.954625226832;9137.588808503568;9157;9182.173604375572;9203.631854725803;5320.6275560710765;5436.456354364572;5490.121580547093;5498.917516237259;5501.229827824068;3553.502509648651;3566.5185199997927;3587.982060089683;3589.3898037333715;3630;1223.8531376234819;1224.0574757436814;1241.8696036916085;1254.3414707278753;1255.4538775632539;13395.732085358239;13550.796738048839;13617.387217575133;13638.454461821515;13657.590272291847;4447.599716025508;4551.613112885442;4611.16998940194;4648.581627653527;4738.905221895612;10682.305650132717;10691.893081069245;10694.946525267324;10695.625653102104;10699;5525.972370138124;5531.778728850882;5533.944660553343;5540.168974653834;5541.33503979525;4268.573142685706;4440.422745043188;4585.633149348044;5019.67372120811;5185.766640501111;3932.8426862925735;3966.345552983725;4052.675785937118;4066.573009833925;4072.1448495815616;1166.422620802708;1167.9357635329968;1179.3606886427872;1188.5483516263937;1194.1223200947297;1045.749020235138;1065.8774240962234;1071.244772435431;1079.1636481726568;1083.1876092930302;6415.967920160369;6445.677716114175;6475.4819614430735;6498.772542960974;6698;994.3983889746744;1107.9944600276947;1136.176272202663;1157.2477889371808;1166.192401777242;4226.978865105716;4262.85080022204;4308.655307575386;4373.365861950063;4424.734515929054;11197.552097915992;11600.709982250512;11606.477708503138;11610.6516804496;11659.417029148508;11144.2199046066;11168.162387820947;11317.60388386419;11323.037541809039;11339;8895;8925.910740892637;9045.095490450902;9049.502277374675;9128.58921348541;6191.876162476815;6197.03945888388;6246.406591373812;6392;6423.132877061536;10861.67414977552;10945.288556243819;10962.342259464458;11018.283811552223;11022.559097636005;11153.21927465102;11168.329900206014;11341.092712583139;11422.885771142182;11459.713507162387;7930.682772689259;7986.161453047579;7988.920110798817;8012.118666946515;8037.0665603825755;8927;9024.458532488072;9027.503487307999;9031.864522032307;9036.864447033167;7973.44185906981;8153.959230203811;8154.796130096795;8192.139825318256;8393.24460798531;7904.802379940547;7953.5227886327;8028.759137225892;8103.513789172668;8174.509529428254;7477.40180785525;7491;7505.324520793155;7510.39916806665;7585.544867307979;5048.343715316985;5143.331366922276;5227.451117632746;5276.577873770166;5366;5522.420145884625;5621;5690.97562438765;5794.275715535558;5824.301083869963 20:08:03 INFO - PROCESS | 27825 | |4;prototype.html;315.7631776167874;331.2728560809024;333.7546903026276;339.1267486222975;340.225985882118;5315.36215654122;5452.809151679692;5496.065668836298;5523.723813809309;5574.693391863446;5382.4886635769635;5383.4616538346145;5387.515123638872;5410.702411367383;5418.160185171295;4300.602524006032;4303.33298338758;4304;4305.50486694031;4307.267764480031;4040;4209.179210054041;4274.145170965804;4299.914001719963;4356.4772227332605;4209.705320627558;4217.177650358166;4219.894502637443;4227.217964676529;4230.9153816923645;4227.408162857202;4237.152569486115;4249.235137675218;4251.022264879064;4261.531231564556;3586.6413358664186;3742.1954279829697;3744.55065392152;3751.8311675974646;3753.7372383933134;5297.30486244402;5304.787808487656;5330.200469929503;5349.786008559692;5379.946200537984;4852.4662287148385;4856.635752318591;4857.247126695368;4895.8776030599165;4910.533499317559;5482.150266708666;5658.8019419320535;5664.357336372447;5667.773289068432;5679.687617181054;4073.6130067643526;4078.163976384864;4080.6327430531096;4087.591240875918;4124.216398884202;5614.635048721861;5736.455036771542;5938.376470470625;5971.641701497881;5972.611780234271;4150.418941348214;4155.958440415618;4163.812628431728;4183.686223533247;4192.979035104866;4217.0511634881905;4221.514525829508;4223.767692776865;4245.978770106191;4277.6791740618955;4626.236670949255;4692.976535117371;4849.975750121227;4891.217405215149;4895.31465594817;5723.313202415654;5727;5784.2769653793275;5787.971060144672;5819.679917604572;1399.8600139986124;1428.9499867504587;1433.1616004637403;1436.1239643817264;1437.360374633278;857.1471385970874;899.4917871402637;912.3750231091591;919.6275508419101;921.3688623293066;4932.224399216279;4967.708395817113;4979.377577802775;4980;5022.940594356296;5168.612354073384;5174.456682048331;5182.393458028024;5184.68180364567;5202.0116177926075;4815.7351345676325;4970.403551573834;4971.229459433794;4991.401031876198;5045.621578381636;2745.958810617842;2753.972460275412;2758.255271076838;2760.7929405294685;2764.654418197725;5296.09966305729;5312.362516498045;5325.417172671251;5347.839564813062;5360.812371566976;5060.721660308718;5205.7917683293;5246.422893481714;5294.655847369909;5338.572914166857;5287.735613219324;5321.627486076015;5335.199720042025;5344.812931547377;5378.354597448253;5107.208382700687;5122.641415100908;5157.638965272395;5158.767855446589;5165.251038599307;4950.430700469348;5123.285617926973;5164.845054648366;5185.325907631984;5221.321228240457;5272.179384029526;5293.9205911910585;5800.274965629296;6016.548758843017;6036.728347224298;4114.835406583703;4145.398917157056;4157.792110394529;4165.375193720966;4167.416561681428;5976.043832986701;5993.730282137401;5994.760209591567;5998.160257564032;6000.109965360897;5564.4713752193475;5764.913526297193;5779.422057794187;5813.273340832396;5840.445157710011;5707.286589176353;5800.59395842287;5811.62224455426;5831.192330377597;5845.240118784531;5500.80747173847;5756.856078598069;5781.277340332458;5805.8548536286935;5811.535077193899;5790.334111577223;5800.8259752207505;5807.593468457337;5807.680577568105;5840.795572155124;5634.7464364104535;5638;5645.745941432563;5650.26546548946;5661.745221465127 20:08:03 INFO - PROCESS | 27825 | |5;yui.html;857.4041041476173;870.6038752367673;889.724185502494;918.1690570034114;919.5471006371075;15653.765193522106;17910.37313694021;18099.638007239846;18112.72830907536;18174.545636359097;14860.182689952075;15311.923440382783;15384.769228461553;15391.461298854543;15418.074915505049;883.4655033704596;909.9863502047475;911.2846415563783;912.803747194353;913.137085454246;895.9148880856341;899.7750562359411;900.3247564326755;900.8333458310234;900.8783814185067;940.1679513630452;941.4069136444031;942.0108885670053;942.4675058591907;944.3672739264675;943.5659596585579;943.8725772020758;944.2493217891792;944.475815922163;944.5607792376578;691.1567887177636;691.9515633905629;692.0899017791588;692.3699433515477;692.4737199728193;14784.316272841306;15003.849961500355;15030.849691503054;15091.54725358241;15114;14386.35261413239;14666.926665366711;14674.55976320712;14767.113973161644;14822.851771482256;12265.693357666041;12494.25034497924;12505.187162834387;12614.306213158272;13382.063255572113;4107.178564287125;4272.594103560157;4319.222539942809;4349.69552131351;4433.246348120827;57386.426135738526;59623.70188149075;59794.40205597932;67211.66394168047;71132.6443367785;8898.332625053146;11940.402979850973;12787.168834025759;12911.5480958166;12957.093003489665;25435;25523.617145742828;26007.869960650452;26069.608955865297;26138.73861261401;9457.290703197288;9889.06053924876;9969.401835889725;10152.340097893613;10174.643887464052;5301.469853014668;5333.333333333333;5411.945880541144;5420.620556561003;5599.076152434803;3599.9820000900354;3680.2823449427106;3704.81475926208;3708.035910663193;3712.870049548253;627.6171535363425;658.9176352955881;659.9241087275025;660.9966950165218;668.488606216245;580.9128630705344;593.5370411079365;685.0067402266733;762.089303282572;762.2073044034253;10801.67594972152;11282.323060616389;11331.830022549668;11798.764024719629;12154.5138194473;786.4534148766552;805.3838813307824;813.2111851504031;816.5549775372436;834.8038211020405;5328.866778330573;5377.381601115843;5486.341639003345;5689.9146512802345;5999.5800293979;115978;116705.41647291709;117541;121101.39449302696;130751;92547;103985;107332;107487.92512074935;107489;83777;89111.55444222868;91350.08649913549;93328.53335733413;94710.52644736732;9832.754181145528;11264.15518836107;11305.7738845221;11551.249168804;11634.70913227176;46209.537904621866;50862;51618.741906290226;52853.20720189124;53543.73228133834;71756.64121679358;73777;74726.62636686781;75051.24948750442;75476.62261688657;14211.64470888236;14223.786643200567;14607.780883286547;14838.109713417229;15042.32309546095;15514.146721930401;15547.144907030222;15842.366305348118;16028.278727457526;16131.354745810506;14238.430462781373;14311.785323219952;14565.126092434488;14745.26273686333;14759.335829887466;14810.259487025822;15147.166905819844;15242.847571524142;15249.6187595311;15341.769873451683;14498.202598857164;14723.779143313073;14739;14759.040662356912;14771.187584682946;12086.335251560897;12363.33932689505;12451.439685214005;12681.175723577939;12982.805257921327;12905.677358066123;12944.8058279124;13456.46174153023;13673.79489307681;13806.86193138096 20:08:03 INFO - PROCESS | 27825 | __end_tp_report 20:08:03 INFO - PROCESS | 27825 | __start_cc_report 20:08:03 INFO - PROCESS | 27825 | _x_x_mozilla_cycle_collect,1162 20:08:03 INFO - PROCESS | 27825 | __end_cc_report 20:08:03 INFO - PROCESS | 27825 | __startTimestamp1473390483283__endTimestamp 20:08:03 INFO - PROCESS | 27825 | 20:08:03 INFO - PROCESS | 27825 | ------- Summary: start ------- 20:08:03 INFO - PROCESS | 27825 | Number of tests: 6 20:08:03 INFO - PROCESS | 27825 | 20:08:03 INFO - PROCESS | 27825 | [#0] dojo.html Cycles:165 Average:5483.29 Median:5711.37 stddev:608.07 (10.6%) stddev-sans-first:581.98 20:08:03 INFO - PROCESS | 27825 | Values: 3160.1 3218.6 3246.5 3253.6 3277.3 5404.6 5521.2 5533.4 5540.5 5542.6 5477.0 5531.1 5542.2 5542.9 5552.7 4968.9 5006.9 5048.9 5095.6 5125.4 4942.0 5084.8 5123.9 5127.8 5133.6 4943.1 4986.7 4989.8 5001.5 5027.2 5080.1 5095.6 5104.0 5106.5 5120.5 4921.0 4930.4 4956.7 5003.9 5036.8 5405.4 5412.8 5417.3 5426.9 5457.2 5909.1 5922.5 5924.4 5927.3 5933.5 5848.9 5894.9 5896.6 5946.0 5953.2 5550.1 5556.3 5566.7 5579.5 5601.0 5873.3 6048.1 6060.3 6071.9 6073.9 5683.1 5730.5 5765.7 5822.4 5838.4 5892.0 5892.1 5895.9 5900.5 5906.3 5791.5 5867.9 5873.0 5895.6 5896.5 5796.5 5825.8 5845.2 5897.9 5932.4 4510.3 4510.6 4513.7 4527.4 4528.2 3967.0 4062.2 4068.2 4073.4 4109.6 5440.0 5611.5 5636.9 5655.7 5722.7 5906.8 5937.5 5937.5 5943.9 5957.8 5678.9 5691.1 5700.1 5704.3 5711.4 5013.5 5090.7 5091.2 5095.0 5195.6 6109.4 6113.1 6113.3 6117.6 6128.8 5912.0 5937.3 5944.5 5945.4 5958.2 5698.6 5894.0 5954.8 6027.0 6078.9 5753.6 5766.3 5771.7 5772.9 5773.0 5836.9 5850.4 5875.8 5882.4 5886.2 5668.5 5728.6 5738.6 5753.2 5757.2 5757.8 5784.6 5791.2 5798.9 5808.6 5734.2 5750.3 5755.8 5781.4 5787.6 5625.4 5711.3 5732.7 5761.5 5830.7 5670.9 5691.4 5695.9 5696.9 5723.1 20:08:03 INFO - PROCESS | 27825 | 20:08:03 INFO - PROCESS | 27825 | [#1] ext.html Cycles:180 Average:64868.01 Median:21625.33 stddev:129335.11 (598.1%) stddev-sans-first:129607.87 20:08:03 INFO - PROCESS | 27825 | Values: 582.9 618.3 642.0 654.2 667.4 35971.8 45663.5 46637.8 46855.8 50442.5 47004.3 47499.0 47512.8 47583.5 47614.5 8519.5 9260.8 9453.1 9455.5 10993.8 10842.2 11268.9 11279.6 11280.4 11280.8 11244.3 11271.2 11286.4 11293.5 11298.2 11250.7 11308.8 11311.7 11319.7 11321.7 11019.6 11109.0 11112.0 11114.3 11116.7 45266.5 45536.8 45550.0 45625.8 45631.1 3903.8 3918.9 3972.8 4065.7 4236.6 34134.3 34717.0 34751.3 34811.1 35057.3 4647.3 4654.9 4659.2 4693.8 4702.2 371048.0 371667.0 372003.0 372603.0 374503.0 67139.3 67593.7 67609.7 67699.7 67753.7 39979.8 40903.4 40996.0 42531.0 69172.7 20021.7 20225.7 20233.0 20241.7 20253.4 14118.9 15088.7 15398.0 15443.6 15517.9 21157.3 21260.3 21307.9 21339.7 21365.7 7204.4 7240.8 7278.9 7315.2 7523.6 1003.2 1098.8 1125.6 1146.9 1179.6 32746.0 33021.7 33159.5 33236.8 33814.7 1212.8 1221.0 1255.1 1257.9 1352.0 17819.5 17947.0 17982.6 17985.1 18104.5 630410.0 639493.0 640214.0 640425.6 642055.0 62109.4 62244.7 62361.0 62596.7 62697.4 1362.0 1367.3 1381.2 1423.7 1457.1 27072.5 36093.8 36737.0 39056.6 39424.4 61593.7 62044.7 62159.0 62359.1 62533.0 402150.0 406114.0 406462.0 407696.0 407862.0 27774.7 30299.0 30393.2 30714.8 31746.0 52393.7 53065.2 53543.5 53580.0 53902.0 43794.0 43948.0 44230.3 45780.8 45836.3 44845.6 45075.8 45563.8 45817.2 45828.3 45284.3 45731.0 46594.8 46956.0 47099.3 35866.6 38278.2 38834.8 39331.8 39715.4 29791.0 29993.3 30125.4 30169.7 30473.4 20:08:03 INFO - PROCESS | 27825 | 20:08:03 INFO - PROCESS | 27825 | [#2] jquery.html Cycles:180 Average:29625.23 Median:2139.48 stddev:83413.22 (3898.8%) stddev-sans-first:83618.39 20:08:03 INFO - PROCESS | 27825 | Values: 420.7 422.6 430.7 430.8 445.4 18915.5 20017.2 20406.8 20417.3 20731.8 19360.0 19580.7 19790.8 19914.7 19992.4 5377.8 5568.3 5573.0 5574.0 5583.8 5508.1 5565.3 5574.6 5953.2 5975.9 5849.4 5889.8 5918.6 5930.1 5934.9 5487.0 5489.8 5503.9 5526.9 5856.7 5339.1 5381.8 5385.5 5387.2 5392.8 14458.1 14552.9 14675.2 14685.5 14698.8 2874.2 3013.2 3061.9 3136.0 3189.1 10402.4 10642.8 10743.8 10809.7 11229.8 1970.9 2061.0 2066.2 2086.3 2149.2 67992.4 75006.2 80132.0 89752.6 91312.5 9788.5 10262.3 10355.6 11753.6 12091.5 13934.7 13991.4 14068.6 14109.4 14189.6 8821.4 8829.6 8890.7 8908.9 9028.0 9052.4 9087.4 9159.1 9317.1 9392.8 4364.0 4364.5 4405.6 4424.9 4437.4 2044.7 2052.3 2109.5 2110.2 2129.7 899.1 996.7 1013.9 1056.3 1148.4 20061.8 20569.2 20978.9 21022.8 21128.3 1046.0 1050.9 1076.9 1097.4 1111.6 10906.8 11751.3 11800.6 11817.7 11887.7 500298.0 504945.0 506656.0 507421.0 511465.0 89081.0 91147.5 91198.5 91294.0 91377.0 1042.5 1054.4 1059.5 1062.6 1066.2 18136.8 18162.0 18451.3 18778.3 19016.3 35280.3 41369.6 45699.3 46801.8 46857.7 62942.7 63741.7 64038.0 64680.7 64940.4 8610.4 8749.0 8758.9 8887.1 8935.9 10189.5 13040.7 13600.5 13743.8 13902.3 11880.2 12093.2 12110.2 12161.8 12369.9 11482.2 11801.1 11947.6 12126.3 12443.6 12102.7 12184.3 12232.8 12313.6 12549.3 8687.0 8834.8 8857.2 9161.1 9222.1 10978.9 12199.8 12244.6 12308.7 12360.8 20:08:03 INFO - PROCESS | 27825 | 20:08:03 INFO - PROCESS | 27825 | [#3] mootools.html Cycles:180 Average:6119.32 Median:4265.71 stddev:3547.07 (83.2%) stddev-sans-first:3539.21 20:08:03 INFO - PROCESS | 27825 | Values: 1389.0 1536.0 1567.2 1743.2 1889.9 8548.3 8902.7 8951.5 9241.0 9256.6 9132.5 9235.4 9247.3 9247.5 9252.9 2350.4 2370.5 2372.3 2375.0 2375.2 2313.0 2335.8 2366.0 2378.5 2381.3 2354.2 2368.0 2374.7 2387.0 2390.4 2288.4 2304.9 2316.9 2320.1 2349.4 2317.7 2324.4 2343.0 2345.2 2348.3 9075.0 9137.6 9157.0 9182.2 9203.6 5320.6 5436.5 5490.1 5498.9 5501.2 3553.5 3566.5 3588.0 3589.4 3630.0 1223.9 1224.1 1241.9 1254.3 1255.5 13395.7 13550.8 13617.4 13638.5 13657.6 4447.6 4551.6 4611.2 4648.6 4738.9 10682.3 10691.9 10694.9 10695.6 10699.0 5526.0 5531.8 5533.9 5540.2 5541.3 4268.6 4440.4 4585.6 5019.7 5185.8 3932.8 3966.3 4052.7 4066.6 4072.1 1166.4 1167.9 1179.4 1188.5 1194.1 1045.7 1065.9 1071.2 1079.2 1083.2 6416.0 6445.7 6475.5 6498.8 6698.0 994.4 1108.0 1136.2 1157.2 1166.2 4227.0 4262.9 4308.7 4373.4 4424.7 11197.6 11600.7 11606.5 11610.7 11659.4 11144.2 11168.2 11317.6 11323.0 11339.0 8895.0 8925.9 9045.1 9049.5 9128.6 6191.9 6197.0 6246.4 6392.0 6423.1 10861.7 10945.3 10962.3 11018.3 11022.6 11153.2 11168.3 11341.1 11422.9 11459.7 7930.7 7986.2 7988.9 8012.1 8037.1 8927.0 9024.5 9027.5 9031.9 9036.9 7973.4 8154.0 8154.8 8192.1 8393.2 7904.8 7953.5 8028.8 8103.5 8174.5 7477.4 7491.0 7505.3 7510.4 7585.5 5048.3 5143.3 5227.5 5276.6 5366.0 5522.4 5621.0 5691.0 5794.3 5824.3 20:08:03 INFO - PROCESS | 27825 | 20:08:03 INFO - PROCESS | 27825 | [#4] prototype.html Cycles:180 Average:4655.38 Median:5178.43 stddev:1357.00 (26.2%) stddev-sans-first:1321.14 20:08:03 INFO - PROCESS | 27825 | Values: 315.8 331.3 333.8 339.1 340.2 5315.4 5452.8 5496.1 5523.7 5574.7 5382.5 5383.5 5387.5 5410.7 5418.2 4300.6 4303.3 4304.0 4305.5 4307.3 4040.0 4209.2 4274.1 4299.9 4356.5 4209.7 4217.2 4219.9 4227.2 4230.9 4227.4 4237.2 4249.2 4251.0 4261.5 3586.6 3742.2 3744.6 3751.8 3753.7 5297.3 5304.8 5330.2 5349.8 5379.9 4852.5 4856.6 4857.2 4895.9 4910.5 5482.2 5658.8 5664.4 5667.8 5679.7 4073.6 4078.2 4080.6 4087.6 4124.2 5614.6 5736.5 5938.4 5971.6 5972.6 4150.4 4156.0 4163.8 4183.7 4193.0 4217.1 4221.5 4223.8 4246.0 4277.7 4626.2 4693.0 4850.0 4891.2 4895.3 5723.3 5727.0 5784.3 5788.0 5819.7 1399.9 1428.9 1433.2 1436.1 1437.4 857.1 899.5 912.4 919.6 921.4 4932.2 4967.7 4979.4 4980.0 5022.9 5168.6 5174.5 5182.4 5184.7 5202.0 4815.7 4970.4 4971.2 4991.4 5045.6 2746.0 2754.0 2758.3 2760.8 2764.7 5296.1 5312.4 5325.4 5347.8 5360.8 5060.7 5205.8 5246.4 5294.7 5338.6 5287.7 5321.6 5335.2 5344.8 5378.4 5107.2 5122.6 5157.6 5158.8 5165.3 4950.4 5123.3 5164.8 5185.3 5221.3 5272.2 5293.9 5800.3 6016.5 6036.7 4114.8 4145.4 4157.8 4165.4 4167.4 5976.0 5993.7 5994.8 5998.2 6000.1 5564.5 5764.9 5779.4 5813.3 5840.4 5707.3 5800.6 5811.6 5831.2 5845.2 5500.8 5756.9 5781.3 5805.9 5811.5 5790.3 5800.8 5807.6 5807.7 5840.8 5634.7 5638.0 5645.7 5650.3 5661.7 20:08:03 INFO - PROCESS | 27825 | 20:08:03 INFO - PROCESS | 27825 | [#5] yui.html Cycles:180 Average:21536.56 Median:3692.55 stddev:30270.95 (819.8%) stddev-sans-first:30316.05 20:08:03 INFO - PROCESS | 27825 | Values: 857.4 870.6 889.7 918.2 919.5 15653.8 17910.4 18099.6 18112.7 18174.5 14860.2 15311.9 15384.8 15391.5 15418.1 883.5 910.0 911.3 912.8 913.1 895.9 899.8 900.3 900.8 900.9 940.2 941.4 942.0 942.5 944.4 943.6 943.9 944.2 944.5 944.6 691.2 692.0 692.1 692.4 692.5 14784.3 15003.8 15030.8 15091.5 15114.0 14386.4 14666.9 14674.6 14767.1 14822.9 12265.7 12494.3 12505.2 12614.3 13382.1 4107.2 4272.6 4319.2 4349.7 4433.2 57386.4 59623.7 59794.4 67211.7 71132.6 8898.3 11940.4 12787.2 12911.5 12957.1 25435.0 25523.6 26007.9 26069.6 26138.7 9457.3 9889.1 9969.4 10152.3 10174.6 5301.5 5333.3 5411.9 5420.6 5599.1 3600.0 3680.3 3704.8 3708.0 3712.9 627.6 658.9 659.9 661.0 668.5 580.9 593.5 685.0 762.1 762.2 10801.7 11282.3 11331.8 11798.8 12154.5 786.5 805.4 813.2 816.6 834.8 5328.9 5377.4 5486.3 5689.9 5999.6 115978.0 116705.4 117541.0 121101.4 130751.0 92547.0 103985.0 107332.0 107487.9 107489.0 83777.0 89111.6 91350.1 93328.5 94710.5 9832.8 11264.2 11305.8 11551.2 11634.7 46209.5 50862.0 51618.7 52853.2 53543.7 71756.6 73777.0 74726.6 75051.2 75476.6 14211.6 14223.8 14607.8 14838.1 15042.3 15514.1 15547.1 15842.4 16028.3 16131.4 14238.4 14311.8 14565.1 14745.3 14759.3 14810.3 15147.2 15242.8 15249.6 15341.8 14498.2 14723.8 14739.0 14759.0 14771.2 12086.3 12363.3 12451.4 12681.2 12982.8 12905.7 12944.8 13456.5 13673.8 13806.9 20:08:03 INFO - PROCESS | 27825 | -------- Summary: end -------- 20:08:03 INFO - PROCESS | 27825 | 20:08:04 INFO - TEST-INFO | 27825: exit 0 20:08:04 INFO - TEST-OK | dromaeo_css | took 1113466ms 20:08:04 INFO - TEST-START | kraken 20:08:04 INFO - operating with platform_type : linux_ 20:08:04 INFO - Initialising browser for kraken test... 20:08:04 INFO - TEST-INFO | started process 28026 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMqNmH3/profile http://localhost:35035/getInfo.html) 20:08:08 INFO - PROCESS | 28026 | __metrics Screen width/height:1600/1200 20:08:08 INFO - PROCESS | 28026 | colorDepth:24 20:08:08 INFO - PROCESS | 28026 | Browser inner width/height: 1024/697 20:08:08 INFO - PROCESS | 28026 | __metrics 20:08:09 INFO - PROCESS | 28026 | 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" 20:08:09 INFO - PROCESS | 28026 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:08:10 INFO - TEST-INFO | 28026: exit 0 20:08:10 INFO - Browser initialized. 20:08:10 INFO - Running cycle 1/1 for kraken test... 20:08:10 INFO - TEST-INFO | started process 28113 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMqNmH3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:08:22 INFO - PROCESS | 28113 | RSS: Main: 145715200 20:08:22 INFO - PROCESS | 28113 | 20:08:24 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35035/tests/kraken/driver.html?testName=audio-beat-detection) 20:08:25 INFO - PROCESS | 28113 | RSS: Main: 200900608 20:08:25 INFO - PROCESS | 28113 | 20:08:27 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35035/tests/kraken/driver.html?testName=audio-dft) 20:08:28 INFO - PROCESS | 28113 | RSS: Main: 185155584 20:08:28 INFO - PROCESS | 28113 | 20:08:31 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35035/tests/kraken/driver.html?testName=audio-fft) 20:08:31 INFO - PROCESS | 28113 | RSS: Main: 276140032 20:08:31 INFO - PROCESS | 28113 | 20:08:34 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35035/tests/kraken/driver.html?testName=audio-oscillator) 20:08:34 INFO - PROCESS | 28113 | RSS: Main: 181870592 20:08:34 INFO - PROCESS | 28113 | 20:08:36 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35035/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:08:37 INFO - PROCESS | 28113 | RSS: Main: 180473856 20:08:37 INFO - PROCESS | 28113 | 20:08:40 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35035/tests/kraken/driver.html?testName=imaging-darkroom) 20:08:40 INFO - PROCESS | 28113 | RSS: Main: 216907776 20:08:40 INFO - PROCESS | 28113 | 20:08:44 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35035/tests/kraken/driver.html?testName=imaging-desaturate) 20:08:44 INFO - PROCESS | 28113 | RSS: Main: 215502848 20:08:44 INFO - PROCESS | 28113 | 20:08:47 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35035/tests/kraken/driver.html?testName=json-parse-financial) 20:08:48 INFO - PROCESS | 28113 | RSS: Main: 215470080 20:08:48 INFO - PROCESS | 28113 | 20:08:49 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35035/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:08:50 INFO - PROCESS | 28113 | RSS: Main: 177520640 20:08:50 INFO - PROCESS | 28113 | 20:08:52 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35035/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:08:52 INFO - PROCESS | 28113 | RSS: Main: 185151488 20:08:52 INFO - PROCESS | 28113 | 20:08:54 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35035/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:08:55 INFO - PROCESS | 28113 | RSS: Main: 186724352 20:08:55 INFO - PROCESS | 28113 | 20:08:57 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35035/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:08:58 INFO - PROCESS | 28113 | RSS: Main: 181932032 20:08:58 INFO - PROCESS | 28113 | 20:09:00 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35035/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:09:01 INFO - PROCESS | 28113 | RSS: Main: 184545280 20:09:01 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | Cycle 1(1): loaded http://localhost:35035/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35035/tests/kraken/driver.html?testName=ai-astar) 20:09:03 INFO - PROCESS | 28113 | RSS: Main: 236032000 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | __start_tp_report 20:09:03 INFO - PROCESS | 28113 | _x_x_mozilla_page_load 20:09:03 INFO - PROCESS | 28113 | _x_x_mozilla_page_load_details 20:09:03 INFO - PROCESS | 28113 | |i|pagename|runs| 20:09:03 INFO - PROCESS | 28113 | |0;ai-astar;112;110;119;110;110;111;110;112;112;121 20:09:03 INFO - PROCESS | 28113 | |1;audio-beat-detection;151;116;142;184;118;141;147;122;173;119 20:09:03 INFO - PROCESS | 28113 | |2;audio-dft;162;162;160;160;160;162;167;159;159;158 20:09:03 INFO - PROCESS | 28113 | |3;audio-fft;74;74;75;168;76;75;75;76;177;75 20:09:03 INFO - PROCESS | 28113 | |4;audio-oscillator;83;84;158;84;83;84;146;83;83;145 20:09:03 INFO - PROCESS | 28113 | |5;imaging-gaussian-blur;117;117;117;117;116;116;116;117;117;116 20:09:03 INFO - PROCESS | 28113 | |6;imaging-darkroom;147;145;146;147;148;146;146;147;146;146 20:09:03 INFO - PROCESS | 28113 | |7;imaging-desaturate;95;95;95;95;95;94;95;95;95;95 20:09:03 INFO - PROCESS | 28113 | |8;json-parse-financial;51;49;50;49;49;50;49;48;49;48 20:09:03 INFO - PROCESS | 28113 | |9;json-stringify-tinderbox;60;61;60;61;60;60;60;61;60;61 20:09:03 INFO - PROCESS | 28113 | |10;stanford-crypto-aes;73;73;72;71;76;71;72;72;71;74 20:09:03 INFO - PROCESS | 28113 | |11;stanford-crypto-ccm;129;118;118;120;117;119;115;116;148;119 20:09:03 INFO - PROCESS | 28113 | |12;stanford-crypto-pbkdf2;150;151;149;150;149;149;149;155;152;149 20:09:03 INFO - PROCESS | 28113 | |13;stanford-crypto-sha256-iterative;66;62;64;64;61;61;61;62;61;63 20:09:03 INFO - PROCESS | 28113 | __end_tp_report 20:09:03 INFO - PROCESS | 28113 | __start_cc_report 20:09:03 INFO - PROCESS | 28113 | _x_x_mozilla_cycle_collect,1672 20:09:03 INFO - PROCESS | 28113 | __end_cc_report 20:09:03 INFO - PROCESS | 28113 | __startTimestamp1473390543460__endTimestamp 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | ------- Summary: start ------- 20:09:03 INFO - PROCESS | 28113 | Number of tests: 14 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#0] ai-astar Cycles:10 Average:112.70 Median:112.00 stddev:3.97 (3.5%) stddev-sans-first:4.21 20:09:03 INFO - PROCESS | 28113 | Values: 112.0 110.0 119.0 110.0 110.0 111.0 110.0 112.0 112.0 121.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:144.50 stddev:23.59 (16.3%) stddev-sans-first:24.76 20:09:03 INFO - PROCESS | 28113 | Values: 151.0 116.0 142.0 184.0 118.0 141.0 147.0 122.0 173.0 119.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:2.56 (1.6%) stddev-sans-first:2.68 20:09:03 INFO - PROCESS | 28113 | Values: 162.0 162.0 160.0 160.0 160.0 162.0 167.0 159.0 159.0 158.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#3] audio-fft Cycles:10 Average:94.50 Median:75.00 stddev:41.17 (54.9%) stddev-sans-first:42.99 20:09:03 INFO - PROCESS | 28113 | Values: 74.0 74.0 75.0 168.0 76.0 75.0 75.0 76.0 177.0 75.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#4] audio-oscillator Cycles:10 Average:103.30 Median:83.00 stddev:32.18 (38.8%) stddev-sans-first:33.28 20:09:03 INFO - PROCESS | 28113 | Values: 83.0 84.0 158.0 84.0 83.0 84.0 146.0 83.0 83.0 145.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 20:09:03 INFO - PROCESS | 28113 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 116.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 20:09:03 INFO - PROCESS | 28113 | Values: 147.0 145.0 146.0 147.0 148.0 146.0 146.0 147.0 146.0 146.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 20:09:03 INFO - PROCESS | 28113 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#8] json-parse-financial Cycles:10 Average:49.20 Median:49.00 stddev:0.92 (1.9%) stddev-sans-first:0.71 20:09:03 INFO - PROCESS | 28113 | Values: 51.0 49.0 50.0 49.0 49.0 50.0 49.0 48.0 49.0 48.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 20:09:03 INFO - PROCESS | 28113 | Values: 60.0 61.0 60.0 61.0 60.0 60.0 60.0 61.0 60.0 61.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#10] stanford-crypto-aes Cycles:10 Average:72.50 Median:72.50 stddev:1.58 (2.2%) stddev-sans-first:1.67 20:09:03 INFO - PROCESS | 28113 | Values: 73.0 73.0 72.0 71.0 76.0 71.0 72.0 72.0 71.0 74.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#11] stanford-crypto-ccm Cycles:10 Average:121.90 Median:119.00 stddev:9.94 (8.4%) stddev-sans-first:10.20 20:09:03 INFO - PROCESS | 28113 | Values: 129.0 118.0 118.0 120.0 117.0 119.0 115.0 116.0 148.0 119.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.30 Median:150.00 stddev:1.95 (1.3%) stddev-sans-first:2.06 20:09:03 INFO - PROCESS | 28113 | Values: 150.0 151.0 149.0 150.0 149.0 149.0 149.0 155.0 152.0 149.0 20:09:03 INFO - PROCESS | 28113 | 20:09:03 INFO - PROCESS | 28113 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.50 stddev:1.72 (2.7%) stddev-sans-first:1.27 20:09:03 INFO - PROCESS | 28113 | Values: 66.0 62.0 64.0 64.0 61.0 61.0 61.0 62.0 61.0 63.0 20:09:03 INFO - PROCESS | 28113 | -------- Summary: end -------- 20:09:03 INFO - PROCESS | 28113 | 20:09:04 INFO - TEST-INFO | 28113: exit 0 20:09:04 INFO - TEST-OK | kraken | took 60416ms 20:09:04 INFO - SUITE-END | took 1173s 20:09:04 INFO - Completed test suite (00:19:34) 20:09:04 INFO - javascript benchmark 20:09:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3160.1467603746987, 3218.62985756638, 3246.496792997085, 3253.6095668519783, 3277.3445310937786, 5404.648697834638, 5521.171824226368, 5533.446655334465, 5540.529055030327, 5542.612017158801, 5477.0415177344, 5531.1426728857095, 5542.224088627595, 5542.91685624715, 5552.7223638818095, 4968.87577810554, 5006.899862002758, 5048.899022019557, 5095.5923526118, 5125.359330083739, 4942.01159768046, 5084.77118529667, 5123.871903202413, 5127.794888204486, 5133.589312854962, 4943.1102401567705, 4986.700797952134, 4989.775460104286, 5001.474845141244, 5027.170516864713, 5080.13637681595, 5095.566876815476, 5104.0, 5106.4638212987475, 5120.487951204868, 4920.975395123037, 4930.359053323081, 4956.702597844141, 5003.949960500421, 5036.84889453317, 5405.351357837085, 5412.83761487156, 5417.349918009824, 5426.864328391783, 5457.181422786574, 5909.084091965752, 5922.526197904157, 5924.377940316291, 5927.2590926134235, 5933.525317974551, 5848.912266316009, 5894.941050589482, 5896.557758168155, 5945.970270148665, 5953.196318497034, 5550.111982082847, 5556.333240011225, 5566.693831839207, 5579.525740312119, 5601.047821870292, 5873.2658417697785, 6048.12302216185, 6060.33336333003, 6071.939280607137, 6073.939260607426, 5683.062294721325, 5730.484256416942, 5765.682887441147, 5822.359540450547, 5838.4453476919625, 5892.027815410409, 5892.057270836645, 5895.882082358329, 5900.468957793733, 5906.26171728534, 5791.536677065825, 5867.882642347129, 5872.972229859843, 5895.557833162444, 5896.528277737771, 5796.536277097822, 5825.79609713658, 5845.152452894306, 5897.941020589739, 5932.406759324033, 4510.2783554631105, 4510.616597589242, 4513.661475389359, 4527.4340707411575, 4528.230200865795, 3967.0, 4062.1672557125717, 4068.1863627274665, 4073.3889916512762, 4109.609587089221, 5440.0, 5611.5230205433, 5636.943630563642, 5655.745491452894, 5722.656640601577, 5906.7637294508595, 5937.495312898365, 5937.52499800015, 5943.851403714942, 5957.76168953237, 5678.943210567924, 5691.146328050825, 5700.087985922232, 5704.3154821421695, 5711.371749107595, 5013.49865013503, 5090.720010399389, 5091.1854103343285, 5095.031943930641, 5195.584353251731, 6109.389061093855, 6113.052476866004, 6113.29697084841, 6117.602355846856, 6128.754849805958, 5911.965406053838, 5937.287525496968, 5944.494717949023, 5945.405459454193, 5958.1658567801405, 5698.601097923106, 5893.970530147321, 5954.791582294599, 6027.0, 6078.878422431615, 5753.568482363756, 5766.250387449605, 5771.740271687868, 5772.855678608068, 5772.971135144466, 5836.912446313394, 5850.444207800422, 5875.794347197828, 5882.382349853204, 5886.205362276038, 5668.5465162787705, 5728.627639203439, 5738.5696072795545, 5753.223314852441, 5757.222774925499, 5757.827265182052, 5784.623999440023, 5791.218185544897, 5798.913016304675, 5808.564357673107, 5734.2258795062135, 5750.30996280449, 5755.769769209185, 5781.392953739797, 5787.594868359174, 5625.409332020243, 5711.286089238845, 5732.684702341411, 5761.452661997104, 5830.737616807169, 5670.886582268414, 5691.402402747817, 5695.857603559944, 5696.857578560569, 5723.112917497794], "value": 5443.15982711931, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.8979928512509, 618.2611778924185, 641.9839504012402, 654.1920727901042, 667.4260136282793, 35971.82014089929, 45663.54336456634, 46637.76681116589, 46855.76572117139, 50442.49557504424, 47004.294935575905, 47499.0, 47512.762436187855, 47583.52416475834, 47614.52385476144, 8519.53142577158, 9260.768480787983, 9453.101955314234, 9455.527223638825, 10993.780124397506, 10842.241043126984, 11268.943655281711, 11279.605213817518, 11280.435978201098, 11280.830787538152, 11244.269122507052, 11271.211015228937, 11286.379249141293, 11293.4917928693, 11298.152638552141, 11250.718732031683, 11308.830367544493, 11311.660650180507, 11319.660410187707, 11321.716957076056, 11019.559217631286, 11109.00018998294, 11112.0, 11114.277571957879, 11116.722081947935, 45266.547334526564, 45536.77231613854, 45550.0, 45625.771871140765, 45631.08737825258, 3903.8243279052226, 3918.8628398006217, 3972.8410863565423, 4065.715399922007, 4236.555161708007, 34134.317313653584, 34717.0, 34751.30497390063, 34811.12972175691, 35057.298854022774, 4647.279671650899, 4654.930176047362, 4659.184642687516, 4693.78877950493, 4702.200625893606, 371048.0, 371667.0, 372003.0, 372603.0, 374503.0, 67139.32860671429, 67593.66203169052, 67609.66195168992, 67699.66150169316, 67753.66123169352, 39979.80010099931, 40903.38644920329, 40996.0, 42531.0, 69172.65413672899, 20021.699674504893, 20225.696614550794, 20233.0, 20241.696374554394, 20253.392398228076, 14118.941079419084, 15088.698226035638, 15398.0, 15443.613909652124, 15517.922410387875, 21157.25949591788, 21260.25589104374, 21307.8934605326, 21339.67990480144, 21365.67951480729, 7204.423646108404, 7240.818979525554, 7278.927210727825, 7315.231900650461, 7523.623818809037, 1003.207466101786, 1098.807708650983, 1125.5835340923747, 1146.850909381775, 1179.5576658752968, 32746.0, 33021.66978330234, 33159.502607460905, 33236.83381583125, 33814.66185338164, 1212.7635111153418, 1220.9877901220875, 1255.0712472770085, 1257.88050135237, 1351.959441216765, 17819.46541603754, 17947.0, 17982.609390130638, 17985.1007449627, 18104.547386315448, 630410.0, 639493.0, 640214.0, 640425.5957440459, 642055.0, 62109.37890621126, 62244.68877655673, 62361.0, 62596.687016564625, 62697.37302627006, 1361.9863801362058, 1367.3231750283474, 1381.2196109198471, 1423.7081398313321, 1457.1475686723174, 27072.458550829266, 36093.81953090323, 36737.0, 39056.609433905294, 39424.40863386994, 61593.692031539555, 62044.689776550826, 62159.0, 62359.06461403173, 62533.0, 402150.0, 406114.0, 406462.0, 407696.0, 407862.0, 27774.722252777214, 30299.0, 30393.24016899595, 30714.84642576773, 31746.0, 52393.7380313096, 53065.20402194047, 53543.46456535385, 53580.0, 53902.00629171535, 43793.9844905644, 43948.0, 44230.336544951206, 45780.77109614431, 45836.31245531253, 44845.55154448414, 45075.774621126686, 45563.772181138884, 45817.15109773961, 45828.31257531073, 45284.320735189656, 45731.0, 46594.76702616601, 46956.0, 47099.29351059668, 35866.64133358737, 38278.19068169073, 38834.80582597069, 39331.80334098311, 39715.40426893657, 29791.0, 29993.250168745955, 30125.427367416607, 30169.69830301669, 30473.390532188787], "value": 22097.66649408953, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.72652775695815, 422.5605370414769, 430.68990326964575, 430.8233624214073, 445.3786967180783, 18915.527111822194, 20017.19931202752, 20406.795932040714, 20417.285395011142, 20731.792682073174, 19360.0, 19580.706289405636, 19790.802091979076, 19914.70127948078, 19992.40022799315, 5377.784888604461, 5568.331800183983, 5572.972135139318, 5573.996680597491, 5583.776648934037, 5508.118701007839, 5565.30433695788, 5574.58190635704, 5953.166556682068, 5975.850603734898, 5849.385814489481, 5889.793857214999, 5918.644881307136, 5930.140129681194, 5934.851628709295, 5487.0, 5489.835304940818, 5503.862403439906, 5526.889462210774, 5856.677882716449, 5339.145736682151, 5381.838544843661, 5385.542228910547, 5387.218853266255, 5392.811251606175, 14458.132512049311, 14552.92723536386, 14675.192864392455, 14685.48600798967, 14698.779518307234, 2874.238326843388, 3013.2165636934337, 3061.892833750808, 3135.984320078408, 3189.059227527885, 10402.375857448576, 10642.840357394567, 10743.78512429747, 10809.67570972872, 11229.831552526719, 1970.8718933269292, 2061.0, 2066.163203902422, 2086.2698055680544, 2149.2477632828386, 67992.36623235841, 75006.24993750102, 80132.0, 89752.5512372434, 91312.54343728372, 9788.510574471247, 10262.332948358333, 10355.585776568852, 11753.647390578297, 12091.45588448522, 13934.721305574034, 13991.440342386393, 14068.577942661737, 14109.365078571489, 14189.574312770634, 8821.426607270507, 8829.602667879832, 8890.73327800167, 8908.8663670045, 9028.0, 9052.411593246417, 9087.363884528151, 9159.129882661136, 9317.068293170765, 9392.765180870396, 4363.956360436418, 4364.454443194601, 4405.559444055569, 4424.867253982386, 4437.400950871655, 2044.6524090904581, 2052.3022172461137, 2109.525356794709, 2110.1981247125996, 2129.712488814021, 899.0919171636564, 996.6561536269975, 1013.9036791504795, 1056.3028401255287, 1148.4315263944402, 20061.799382005993, 20569.177232910515, 20978.895105524374, 21022.789772102387, 21128.260510882046, 1045.9581616735397, 1050.9369437833755, 1076.923076923077, 1097.3690128176331, 1111.5776005118003, 10906.781864362669, 11751.33165542283, 11800.645980620595, 11817.704557386134, 11887.702807429883, 500298.0, 504945.0, 506656.0, 507421.0, 511465.0, 89081.0, 91147.5442622796, 91198.54400727953, 91294.0, 91377.0, 1042.5308611125024, 1054.414799786126, 1059.4649701900491, 1062.574970011986, 1066.2269854355686, 18136.818631813512, 18162.0, 18451.261949521868, 18778.342758003404, 19016.334428294944, 35280.29439411146, 41369.586304136574, 45699.31451028171, 46801.76599116983, 46857.7199596025, 62942.68528657327, 63741.6812915951, 64038.0, 64680.676596616715, 64940.35059649343, 8610.354223433142, 8749.0, 8758.91241087581, 8887.066857979818, 8935.910640893768, 10189.490525473548, 13040.739185216433, 13600.455981761015, 13743.793843092562, 13902.30488475552, 11880.168388212742, 12093.153479256367, 12110.212836165621, 12161.817572736592, 12369.93815030955, 11482.196246262682, 11801.114916381133, 11947.641570752892, 12126.333051682243, 12443.564475243324, 12102.697432564257, 12184.329861857683, 12232.755344892874, 12313.56902508408, 12549.290073016939, 8686.957565092229, 8834.823303534022, 8857.247133993682, 9161.083891610786, 9222.123898229658, 10978.890211097787, 12199.756004879675, 12244.571439999558, 12308.692282693004, 12360.75278494443], "value": 9442.920370193326, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.9722205555884, 1535.976960345595, 1567.1772319532245, 1743.2155530011498, 1889.9433017009485, 8548.316134709225, 8902.688405905787, 8951.507667078316, 9241.0, 9256.58345374458, 9132.497712625804, 9235.39969901956, 9247.306452016091, 9247.49138797366, 9252.907470925305, 2350.4241460842077, 2370.5377451396985, 2372.323887692008, 2375.0381095656285, 2375.1686909581595, 2312.9653055204185, 2335.8131349492, 2365.9826274701873, 2378.5361854438393, 2381.285614315707, 2354.1760383865594, 2368.017272831773, 2374.6912901322803, 2386.95226095478, 2390.40239940015, 2288.4050146961745, 2304.942376440594, 2316.9073237070497, 2320.0835669910375, 2349.436135327509, 2317.723300105035, 2324.4305145239355, 2343.0393538649073, 2345.202631105415, 2348.2602980061397, 9074.954625226832, 9137.588808503568, 9157.0, 9182.173604375572, 9203.631854725803, 5320.6275560710765, 5436.456354364572, 5490.121580547093, 5498.917516237259, 5501.229827824068, 3553.502509648651, 3566.5185199997927, 3587.982060089683, 3589.3898037333715, 3630.0, 1223.8531376234819, 1224.0574757436814, 1241.8696036916085, 1254.3414707278753, 1255.4538775632539, 13395.732085358239, 13550.796738048839, 13617.387217575133, 13638.454461821515, 13657.590272291847, 4447.599716025508, 4551.613112885442, 4611.16998940194, 4648.581627653527, 4738.905221895612, 10682.305650132717, 10691.893081069245, 10694.946525267324, 10695.625653102104, 10699.0, 5525.972370138124, 5531.778728850882, 5533.944660553343, 5540.168974653834, 5541.33503979525, 4268.573142685706, 4440.422745043188, 4585.633149348044, 5019.67372120811, 5185.766640501111, 3932.8426862925735, 3966.345552983725, 4052.675785937118, 4066.573009833925, 4072.1448495815616, 1166.422620802708, 1167.9357635329968, 1179.3606886427872, 1188.5483516263937, 1194.1223200947297, 1045.749020235138, 1065.8774240962234, 1071.244772435431, 1079.1636481726568, 1083.1876092930302, 6415.967920160369, 6445.677716114175, 6475.4819614430735, 6498.772542960974, 6698.0, 994.3983889746744, 1107.9944600276947, 1136.176272202663, 1157.2477889371808, 1166.192401777242, 4226.978865105716, 4262.85080022204, 4308.655307575386, 4373.365861950063, 4424.734515929054, 11197.552097915992, 11600.709982250512, 11606.477708503138, 11610.6516804496, 11659.417029148508, 11144.2199046066, 11168.162387820947, 11317.60388386419, 11323.037541809039, 11339.0, 8895.0, 8925.910740892637, 9045.095490450902, 9049.502277374675, 9128.58921348541, 6191.876162476815, 6197.03945888388, 6246.406591373812, 6392.0, 6423.132877061536, 10861.67414977552, 10945.288556243819, 10962.342259464458, 11018.283811552223, 11022.559097636005, 11153.21927465102, 11168.329900206014, 11341.092712583139, 11422.885771142182, 11459.713507162387, 7930.682772689259, 7986.161453047579, 7988.920110798817, 8012.118666946515, 8037.0665603825755, 8927.0, 9024.458532488072, 9027.503487307999, 9031.864522032307, 9036.864447033167, 7973.44185906981, 8153.959230203811, 8154.796130096795, 8192.139825318256, 8393.24460798531, 7904.802379940547, 7953.5227886327, 8028.759137225892, 8103.513789172668, 8174.509529428254, 7477.40180785525, 7491.0, 7505.324520793155, 7510.39916806665, 7585.544867307979, 5048.343715316985, 5143.331366922276, 5227.451117632746, 5276.577873770166, 5366.0, 5522.420145884625, 5621.0, 5690.97562438765, 5794.275715535558, 5824.301083869963], "value": 4870.707877072551, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.7631776167874, 331.2728560809024, 333.7546903026276, 339.1267486222975, 340.225985882118, 5315.36215654122, 5452.809151679692, 5496.065668836298, 5523.723813809309, 5574.693391863446, 5382.4886635769635, 5383.4616538346145, 5387.515123638872, 5410.702411367383, 5418.160185171295, 4300.602524006032, 4303.33298338758, 4304.0, 4305.50486694031, 4307.267764480031, 4040.0, 4209.179210054041, 4274.145170965804, 4299.914001719963, 4356.4772227332605, 4209.705320627558, 4217.177650358166, 4219.894502637443, 4227.217964676529, 4230.9153816923645, 4227.408162857202, 4237.152569486115, 4249.235137675218, 4251.022264879064, 4261.531231564556, 3586.6413358664186, 3742.1954279829697, 3744.55065392152, 3751.8311675974646, 3753.7372383933134, 5297.30486244402, 5304.787808487656, 5330.200469929503, 5349.786008559692, 5379.946200537984, 4852.4662287148385, 4856.635752318591, 4857.247126695368, 4895.8776030599165, 4910.533499317559, 5482.150266708666, 5658.8019419320535, 5664.357336372447, 5667.773289068432, 5679.687617181054, 4073.6130067643526, 4078.163976384864, 4080.6327430531096, 4087.591240875918, 4124.216398884202, 5614.635048721861, 5736.455036771542, 5938.376470470625, 5971.641701497881, 5972.611780234271, 4150.418941348214, 4155.958440415618, 4163.812628431728, 4183.686223533247, 4192.979035104866, 4217.0511634881905, 4221.514525829508, 4223.767692776865, 4245.978770106191, 4277.6791740618955, 4626.236670949255, 4692.976535117371, 4849.975750121227, 4891.217405215149, 4895.31465594817, 5723.313202415654, 5727.0, 5784.2769653793275, 5787.971060144672, 5819.679917604572, 1399.8600139986124, 1428.9499867504587, 1433.1616004637403, 1436.1239643817264, 1437.360374633278, 857.1471385970874, 899.4917871402637, 912.3750231091591, 919.6275508419101, 921.3688623293066, 4932.224399216279, 4967.708395817113, 4979.377577802775, 4980.0, 5022.940594356296, 5168.612354073384, 5174.456682048331, 5182.393458028024, 5184.68180364567, 5202.0116177926075, 4815.7351345676325, 4970.403551573834, 4971.229459433794, 4991.401031876198, 5045.621578381636, 2745.958810617842, 2753.972460275412, 2758.255271076838, 2760.7929405294685, 2764.654418197725, 5296.09966305729, 5312.362516498045, 5325.417172671251, 5347.839564813062, 5360.812371566976, 5060.721660308718, 5205.7917683293, 5246.422893481714, 5294.655847369909, 5338.572914166857, 5287.735613219324, 5321.627486076015, 5335.199720042025, 5344.812931547377, 5378.354597448253, 5107.208382700687, 5122.641415100908, 5157.638965272395, 5158.767855446589, 5165.251038599307, 4950.430700469348, 5123.285617926973, 5164.845054648366, 5185.325907631984, 5221.321228240457, 5272.179384029526, 5293.9205911910585, 5800.274965629296, 6016.548758843017, 6036.728347224298, 4114.835406583703, 4145.398917157056, 4157.792110394529, 4165.375193720966, 4167.416561681428, 5976.043832986701, 5993.730282137401, 5994.760209591567, 5998.160257564032, 6000.109965360897, 5564.4713752193475, 5764.913526297193, 5779.422057794187, 5813.273340832396, 5840.445157710011, 5707.286589176353, 5800.59395842287, 5811.62224455426, 5831.192330377597, 5845.240118784531, 5500.80747173847, 5756.856078598069, 5781.277340332458, 5805.8548536286935, 5811.535077193899, 5790.334111577223, 5800.8259752207505, 5807.593468457337, 5807.680577568105, 5840.795572155124, 5634.7464364104535, 5638.0, 5645.745941432563, 5650.26546548946, 5661.745221465127], "value": 4218.964967793818, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [857.4041041476173, 870.6038752367673, 889.724185502494, 918.1690570034114, 919.5471006371075, 15653.765193522106, 17910.37313694021, 18099.638007239846, 18112.72830907536, 18174.545636359097, 14860.182689952075, 15311.923440382783, 15384.769228461553, 15391.461298854543, 15418.074915505049, 883.4655033704596, 909.9863502047475, 911.2846415563783, 912.803747194353, 913.137085454246, 895.9148880856341, 899.7750562359411, 900.3247564326755, 900.8333458310234, 900.8783814185067, 940.1679513630452, 941.4069136444031, 942.0108885670053, 942.4675058591907, 944.3672739264675, 943.5659596585579, 943.8725772020758, 944.2493217891792, 944.475815922163, 944.5607792376578, 691.1567887177636, 691.9515633905629, 692.0899017791588, 692.3699433515477, 692.4737199728193, 14784.316272841306, 15003.849961500355, 15030.849691503054, 15091.54725358241, 15114.0, 14386.35261413239, 14666.926665366711, 14674.55976320712, 14767.113973161644, 14822.851771482256, 12265.693357666041, 12494.25034497924, 12505.187162834387, 12614.306213158272, 13382.063255572113, 4107.178564287125, 4272.594103560157, 4319.222539942809, 4349.69552131351, 4433.246348120827, 57386.426135738526, 59623.70188149075, 59794.40205597932, 67211.66394168047, 71132.6443367785, 8898.332625053146, 11940.402979850973, 12787.168834025759, 12911.5480958166, 12957.093003489665, 25435.0, 25523.617145742828, 26007.869960650452, 26069.608955865297, 26138.73861261401, 9457.290703197288, 9889.06053924876, 9969.401835889725, 10152.340097893613, 10174.643887464052, 5301.469853014668, 5333.333333333333, 5411.945880541144, 5420.620556561003, 5599.076152434803, 3599.9820000900354, 3680.2823449427106, 3704.81475926208, 3708.035910663193, 3712.870049548253, 627.6171535363425, 658.9176352955881, 659.9241087275025, 660.9966950165218, 668.488606216245, 580.9128630705344, 593.5370411079365, 685.0067402266733, 762.089303282572, 762.2073044034253, 10801.67594972152, 11282.323060616389, 11331.830022549668, 11798.764024719629, 12154.5138194473, 786.4534148766552, 805.3838813307824, 813.2111851504031, 816.5549775372436, 834.8038211020405, 5328.866778330573, 5377.381601115843, 5486.341639003345, 5689.9146512802345, 5999.5800293979, 115978.0, 116705.41647291709, 117541.0, 121101.39449302696, 130751.0, 92547.0, 103985.0, 107332.0, 107487.92512074935, 107489.0, 83777.0, 89111.55444222868, 91350.08649913549, 93328.53335733413, 94710.52644736732, 9832.754181145528, 11264.15518836107, 11305.7738845221, 11551.249168804, 11634.70913227176, 46209.537904621866, 50862.0, 51618.741906290226, 52853.20720189124, 53543.73228133834, 71756.64121679358, 73777.0, 74726.62636686781, 75051.24948750442, 75476.62261688657, 14211.64470888236, 14223.786643200567, 14607.780883286547, 14838.109713417229, 15042.32309546095, 15514.146721930401, 15547.144907030222, 15842.366305348118, 16028.278727457526, 16131.354745810506, 14238.430462781373, 14311.785323219952, 14565.126092434488, 14745.26273686333, 14759.335829887466, 14810.259487025822, 15147.166905819844, 15242.847571524142, 15249.6187595311, 15341.769873451683, 14498.202598857164, 14723.779143313073, 14739.0, 14759.040662356912, 14771.187584682946, 12086.335251560897, 12363.33932689505, 12451.439685214005, 12681.175723577939, 12982.805257921327, 12905.677358066123, 12944.8058279124, 13456.46174153023, 13673.79489307681, 13806.86193138096], "value": 8190.873972596762, "unit": "score"}], "name": "dromaeo_css", "value": 7590.083459195364, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 119.0, 110.0, 110.0, 111.0, 110.0, 112.0, 112.0, 121.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 116.0, 142.0, 184.0, 118.0, 141.0, 147.0, 122.0, 173.0, 119.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 160.0, 160.0, 162.0, 167.0, 159.0, 159.0, 158.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 168.0, 76.0, 75.0, 75.0, 76.0, 177.0, 75.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 158.0, 84.0, 83.0, 84.0, 146.0, 83.0, 83.0, 145.0], "value": 103.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 146.0, 147.0, 148.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 49.0, 49.0, 50.0, 49.0, 48.0, 49.0, 48.0], "value": 49.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0, 61.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 71.0, 76.0, 71.0, 72.0, 72.0, 71.0, 74.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 118.0, 118.0, 120.0, 117.0, 119.0, 115.0, 116.0, 148.0, 119.0], "value": 121.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 149.0, 150.0, 149.0, 149.0, 149.0, 155.0, 152.0, 149.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 64.0, 64.0, 61.0, 61.0, 61.0, 62.0, 61.0, 63.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1487.4, "alertThreshold": 2.0}]} 20:09:04 INFO - Return code: 0 20:09:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:09:04 INFO - # TBPL SUCCESS # 20:09:04 INFO - Running post-action listener: _resource_record_post_action 20:09:04 INFO - [mozharness: 2016-09-09 03:09:04.767456Z] Finished run-tests step (success) 20:09:04 INFO - Running post-run listener: _resource_record_post_run 20:09:04 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 262144; Write bytes: 327421952; Read time: 20; Write time: 233456 20:09:04 INFO - TinderboxPrint: CPU usage
13.0% 20:09:04 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 20 20:09:04 INFO - TinderboxPrint: I/O write bytes / time
327,421,952 / 233,456 20:09:04 INFO - TinderboxPrint: CPU idle
8,269.1 (87.0%) 20:09:04 INFO - TinderboxPrint: CPU user
1,190.2 (12.5%) 20:09:04 INFO - TinderboxPrint: Swap in / out
0 / 0 20:09:04 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78499840; Read time: 0; Write time: 86708 20:09:04 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248016896; Read time: 20; Write time: 146716 20:09:05 INFO - Running post-run listener: _upload_blobber_files 20:09:05 INFO - Blob upload gear active. 20:09:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:09:05 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 20:09:05 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'] 20:09:05 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 20:09:05 INFO - (blobuploader) - INFO - Open directory for files ... 20:09:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:09:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:09:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:09:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:09:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:09:06 INFO - (blobuploader) - INFO - Done attempting. 20:09:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:09:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:09:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:09:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:09:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:09:10 INFO - (blobuploader) - INFO - Done attempting. 20:09:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:09:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:09:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:09:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:09:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:09:10 INFO - (blobuploader) - INFO - Done attempting. 20:09:10 INFO - (blobuploader) - INFO - Iteration through files over. 20:09:10 INFO - Return code: 0 20:09:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:09:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:09:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/048d971272b7225eef4a25acdee3a9fd8067b2d2c78756cf030ff1e35963eca944e2077dcc219bcb5db600609a195fb02bf669af88ec37537737cac00beee636", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dd169b6c6755054a9b9a5aba6e11f10ac040adcefe318f82e49998e2737a43bf920e224361e6a50d079a06359ddad5cd9c978f499799c3c441e081b4121c7e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b75a1dfb6c2e3aefc72f3e4191eeb902baad59188ce984728ebba181722ab9be69c02c7c3e784a8df798f66a50dff700798d27fd39f18b652107c6af157ae31c"} 20:09:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:09:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:09:10 INFO - Contents: 20:09:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/048d971272b7225eef4a25acdee3a9fd8067b2d2c78756cf030ff1e35963eca944e2077dcc219bcb5db600609a195fb02bf669af88ec37537737cac00beee636", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dd169b6c6755054a9b9a5aba6e11f10ac040adcefe318f82e49998e2737a43bf920e224361e6a50d079a06359ddad5cd9c978f499799c3c441e081b4121c7e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b75a1dfb6c2e3aefc72f3e4191eeb902baad59188ce984728ebba181722ab9be69c02c7c3e784a8df798f66a50dff700798d27fd39f18b652107c6af157ae31c"} 20:09:10 INFO - Running post-run listener: copy_logs_to_upload_dir 20:09:10 INFO - Copying logs to upload dir... 20:09:10 INFO - mkdir: /builds/slave/test/build/upload/logs 20:09:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.064469 ========= master_lag: 0.40 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-08 20:09:10.393517) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 20:09:10.399165) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303 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/048d971272b7225eef4a25acdee3a9fd8067b2d2c78756cf030ff1e35963eca944e2077dcc219bcb5db600609a195fb02bf669af88ec37537737cac00beee636", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dd169b6c6755054a9b9a5aba6e11f10ac040adcefe318f82e49998e2737a43bf920e224361e6a50d079a06359ddad5cd9c978f499799c3c441e081b4121c7e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b75a1dfb6c2e3aefc72f3e4191eeb902baad59188ce984728ebba181722ab9be69c02c7c3e784a8df798f66a50dff700798d27fd39f18b652107c6af157ae31c"} build_url:https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011268 build_url: 'https://queue.taskcluster.net/v1/task/MOlw47niR0SptCGlNlORgg/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/048d971272b7225eef4a25acdee3a9fd8067b2d2c78756cf030ff1e35963eca944e2077dcc219bcb5db600609a195fb02bf669af88ec37537737cac00beee636", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dd169b6c6755054a9b9a5aba6e11f10ac040adcefe318f82e49998e2737a43bf920e224361e6a50d079a06359ddad5cd9c978f499799c3c441e081b4121c7e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b75a1dfb6c2e3aefc72f3e4191eeb902baad59188ce984728ebba181722ab9be69c02c7c3e784a8df798f66a50dff700798d27fd39f18b652107c6af157ae31c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 20:09:10.432103) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:09:10.432509) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1473388218.608808-838901303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.34 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 20:09:10.781773) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 20:09:10.782126) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 20:09:10.782605) ========= ========= Total master_lag: 0.82 =========