builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-068 starttime: 1472737945.53 results: success (0) buildid: 20160830093921 builduid: 86ba142624df48b3b49a4204efa8ff7c revision: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.533252) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.533855) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.534132) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.072390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.075680) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 06:52:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.0M=0s 2016-09-01 06:52:26 (38.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137861 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.310754) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.311029) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017731 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.439192) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.439500) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c819e284a13937fc955c2efb6da7fdf9c5bb8b88 --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 c819e284a13937fc955c2efb6da7fdf9c5bb8b88 --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=97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 06:52:26,618 truncating revision to first 12 chars 2016-09-01 06:52:26,618 Setting DEBUG logging. 2016-09-01 06:52:26,618 attempt 1/10 2016-09-01 06:52:26,618 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c819e284a139?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 06:52:26,985 unpacking tar archive at: mozilla-aurora-c819e284a139/testing/mozharness/ program finished with exit code 0 elapsedTime=0.736606 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.203492) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.203807) ========= script_repo_revision: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.204157) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.204431) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.215877) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 24 secs) (at 2016-09-01 06:52:27.216132) ========= /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=97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:52:27 INFO - MultiFileLogger online at 20160901 06:52:27 in /builds/slave/test 06:52:27 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 06:52:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:52:27 INFO - {'append_to_log': False, 06:52:27 INFO - 'base_work_dir': '/builds/slave/test', 06:52:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:52:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:52:27 INFO - 'branch': 'Mozilla-Aurora', 06:52:27 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:27 INFO - 'config_files': ('talos/linux_config.py',), 06:52:27 INFO - 'default_actions': ('clobber', 06:52:27 INFO - 'read-buildbot-config', 06:52:27 INFO - 'download-and-extract', 06:52:27 INFO - 'populate-webroot', 06:52:27 INFO - 'create-virtualenv', 06:52:27 INFO - 'install', 06:52:27 INFO - 'run-tests'), 06:52:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:27 INFO - 'download_minidump_stackwalk': True, 06:52:27 INFO - 'download_symbols': 'ondemand', 06:52:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:52:27 INFO - 'tooltool.py': '/tools/tooltool.py', 06:52:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:52:27 INFO - '/tools/misc-python/virtualenv.py')}, 06:52:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:27 INFO - 'installer_path': 'installer.exe', 06:52:27 INFO - 'log_level': 'info', 06:52:27 INFO - 'log_name': 'talos', 06:52:27 INFO - 'log_to_console': True, 06:52:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:52:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:52:27 INFO - 'opt_config_files': (), 06:52:27 INFO - 'pip_index': False, 06:52:27 INFO - 'sps_profile': False, 06:52:27 INFO - 'sps_profile_interval': 0, 06:52:27 INFO - 'suite': 'dromaeojs', 06:52:27 INFO - 'system_bits': '32', 06:52:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:27 INFO - 'title': 'talos-linux64-ix-068', 06:52:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:52:27 INFO - 'use_talos_json': True, 06:52:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:52:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:27 INFO - 'work_dir': 'build'} 06:52:27 INFO - [mozharness: 2016-09-01 13:52:27.584178Z] Running clobber step. 06:52:27 INFO - Running pre-action listener: _resource_record_pre_action 06:52:27 INFO - Running main action method: clobber 06:52:27 INFO - rmtree: /builds/slave/test/build 06:52:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:52:27 INFO - Running post-action listener: _resource_record_post_action 06:52:27 INFO - [mozharness: 2016-09-01 13:52:27.996636Z] Finished clobber step (success) 06:52:27 INFO - [mozharness: 2016-09-01 13:52:27.996738Z] Running read-buildbot-config step. 06:52:27 INFO - Running pre-action listener: _resource_record_pre_action 06:52:27 INFO - Running main action method: read_buildbot_config 06:52:27 INFO - Using buildbot properties: 06:52:27 INFO - { 06:52:27 INFO - "project": "", 06:52:27 INFO - "product": "firefox", 06:52:27 INFO - "script_repo_revision": "production", 06:52:27 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:52:27 INFO - "repository": "", 06:52:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:52:27 INFO - "buildid": "20160830093921", 06:52:27 INFO - "pgo_build": "True", 06:52:27 INFO - "basedir": "/builds/slave/test", 06:52:27 INFO - "buildnumber": 721, 06:52:27 INFO - "slavename": "talos-linux64-ix-068", 06:52:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:52:27 INFO - "platform": "ubuntu64_hw", 06:52:27 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:52:27 INFO - "branch": "mozilla-aurora", 06:52:27 INFO - "repo_path": "releases/mozilla-aurora", 06:52:27 INFO - "revision": "c819e284a13937fc955c2efb6da7fdf9c5bb8b88", 06:52:27 INFO - "stage_platform": "linux64-pgo", 06:52:27 INFO - "builduid": "86ba142624df48b3b49a4204efa8ff7c", 06:52:27 INFO - "slavebuilddir": "test" 06:52:27 INFO - } 06:52:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:52:27 INFO - Running post-action listener: _resource_record_post_action 06:52:27 INFO - [mozharness: 2016-09-01 13:52:27.999152Z] Finished read-buildbot-config step (success) 06:52:27 INFO - [mozharness: 2016-09-01 13:52:27.999241Z] Running download-and-extract step. 06:52:27 INFO - Running pre-action listener: _resource_record_pre_action 06:52:27 INFO - Running main action method: download_and_extract 06:52:27 INFO - mkdir: /builds/slave/test/build/tests 06:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:52:27 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:52:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:28 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:52:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:58 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:53:58 INFO - Downloaded 1361 bytes. 06:53:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - Using the following test package requirements: 06:53:58 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:58 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:53:58 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:53:58 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'jsshell-linux-x86_64.zip'], 06:53:58 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:53:58 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:58 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:53:58 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:53:58 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:53:58 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:53:58 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:58 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:53:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:53:58 INFO - retry: Failed, sleeping 30 seconds before retrying 06:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:54:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:54:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:54:28 INFO - retry: Failed, sleeping 60 seconds before retrying 06:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:55:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:55:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 06:55:28 INFO - Caught exception: HTTP Error 404: Not Found 06:55:28 INFO - Caught exception: HTTP Error 404: Not Found 06:55:28 INFO - Caught exception: HTTP Error 404: Not Found 06:55:28 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:28 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:55:29 INFO - Downloaded 21769265 bytes. 06:55:29 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'] 06:55:29 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 06:55:30 INFO - Return code: 0 06:55:30 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 06:55: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')]} 06:55:30 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:55:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:55:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:55:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:55:30 INFO - retry: Failed, sleeping 30 seconds before retrying 06:56:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:56:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:56:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:56:00 INFO - retry: Failed, sleeping 60 seconds before retrying 06:57:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:57:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:57:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 06:57:00 INFO - Caught exception: HTTP Error 404: Not Found 06:57:00 INFO - Caught exception: HTTP Error 404: Not Found 06:57:00 INFO - Caught exception: HTTP Error 404: Not Found 06:57:00 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:00 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:57:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:57:01 INFO - Downloaded 21769265 bytes. 06:57:01 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'] 06:57:01 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 06:57:03 INFO - Return code: 0 06:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:03 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:57:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:57:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:57:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:57:03 INFO - retry: Failed, sleeping 30 seconds before retrying 06:57:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:57:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:57:33 INFO - retry: Failed, sleeping 60 seconds before retrying 06:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:58:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:58:34 INFO - Downloaded 14413723 bytes. 06:58:34 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'] 06:58:34 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 06:58:34 INFO - Return code: 0 06:58:34 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')]} 06:58:34 INFO - https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:58:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:58:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:58:34 INFO - retry: Failed, sleeping 30 seconds before retrying 06:59:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 06:59:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:59:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:59:04 INFO - retry: Failed, sleeping 60 seconds before retrying 07:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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 07:00:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5E-LrHRSJO8XO67pPY81g/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! 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - trying https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - Downloading https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 07:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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 07:00:05 INFO - Downloaded 63875997 bytes. 07:00:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:05 INFO - mkdir: /builds/slave/test/properties 07:00:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:05 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:05 INFO - Contents: 07:00:05 INFO - build_url:https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:06 INFO - Running post-action listener: _resource_record_post_action 07:00:06 INFO - Running post-action listener: set_extra_try_arguments 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.539429Z] Finished download-and-extract step (success) 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.539659Z] Running populate-webroot step. 07:00:06 INFO - Running pre-action listener: _resource_record_pre_action 07:00:06 INFO - Running main action method: populate_webroot 07:00:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:06 INFO - '--tppagecycles', 07:00:06 INFO - '7'], 07:00:06 INFO - 'tests': ['tsvgm']}}, 07:00:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['damp', 'tps']}, 07:00:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'tests': ['damp', 'tps']}, 07:00:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['dromaeo_dom']}, 07:00:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['basic_compositor_video']}, 07:00:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:00:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['a11yr', 07:00:06 INFO - 'ts_paint', 07:00:06 INFO - 'tpaint', 07:00:06 INFO - 'sessionrestore', 07:00:06 INFO - 'sessionrestore_no_auto_restore', 07:00:06 INFO - 'tabpaint']}, 07:00:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tsvgx', 07:00:06 INFO - 'tsvgr_opacity', 07:00:06 INFO - 'tart', 07:00:06 INFO - 'tscrollx', 07:00:06 INFO - 'cart']}, 07:00:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:06 INFO - 'tsvgr_opacity', 07:00:06 INFO - 'tart', 07:00:06 INFO - 'tscrollx', 07:00:06 INFO - 'cart']}, 07:00:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s'], 07:00:06 INFO - 'tests': ['tp5o']}, 07:00:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'tests': ['tp5o']}, 07:00:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--disable-e10s', 07:00:06 INFO - '--xperf_path', 07:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:06 INFO - 'tests': ['tp5n']}, 07:00:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:06 INFO - 'talos_options': ['--xperf_path', 07:00:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:06 INFO - 'tests': ['tp5n']}}, 07:00:06 INFO - 'talos.zip': {'path': '', 07:00:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:06 INFO - Running post-action listener: _resource_record_post_action 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.558537Z] Finished populate-webroot step (success) 07:00:06 INFO - [mozharness: 2016-09-01 14:00:06.558626Z] Running create-virtualenv step. 07:00:06 INFO - Running pre-action listener: _resource_record_pre_action 07:00:06 INFO - Running main action method: create_virtualenv 07:00:06 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:06 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 07:00:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:06 INFO - Using real prefix '/usr' 07:00:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:07 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:09 INFO - Installing pip.................done. 07:00:09 INFO - Return code: 0 07:00:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:00:09 INFO - Using env: {'DISPLAY': ':0', 07:00:09 INFO - 'HOME': '/home/cltbld', 07:00:09 INFO - 'LANG': 'en_US.UTF-8', 07:00:09 INFO - 'LANGUAGE': 'en_US:en', 07:00:09 INFO - 'LOGNAME': 'cltbld', 07:00:09 INFO - 'MAIL': '/var/mail/cltbld', 07:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:09 INFO - 'MOZ_NO_REMOTE': '1', 07:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:09 INFO - 'NO_EM_RESTART': '1', 07:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:09 INFO - 'PWD': '/builds/slave/test', 07:00:09 INFO - 'SHELL': '/bin/bash', 07:00:09 INFO - 'SHLVL': '1', 07:00:09 INFO - 'TERM': 'linux', 07:00:09 INFO - 'TMOUT': '86400', 07:00:09 INFO - 'USER': 'cltbld', 07:00:09 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:09 INFO - '_': '/tools/buildbot/bin/python'} 07:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:09 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 07:00:09 INFO - Cleaning up... 07:00:09 INFO - Return code: 0 07:00:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:00:09 INFO - Using env: {'DISPLAY': ':0', 07:00:09 INFO - 'HOME': '/home/cltbld', 07:00:09 INFO - 'LANG': 'en_US.UTF-8', 07:00:09 INFO - 'LANGUAGE': 'en_US:en', 07:00:09 INFO - 'LOGNAME': 'cltbld', 07:00:09 INFO - 'MAIL': '/var/mail/cltbld', 07:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:09 INFO - 'MOZ_NO_REMOTE': '1', 07:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:09 INFO - 'NO_EM_RESTART': '1', 07:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:09 INFO - 'PWD': '/builds/slave/test', 07:00:09 INFO - 'SHELL': '/bin/bash', 07:00:09 INFO - 'SHLVL': '1', 07:00:09 INFO - 'TERM': 'linux', 07:00:09 INFO - 'TMOUT': '86400', 07:00:09 INFO - 'USER': 'cltbld', 07:00:09 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:09 INFO - '_': '/tools/buildbot/bin/python'} 07:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:09 INFO - Downloading/unpacking psutil>=3.1.1 07:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:11 INFO - Installing collected packages: psutil 07:00:11 INFO - Running setup.py install for psutil 07:00:12 INFO - building 'psutil._psutil_linux' extension 07:00:12 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 07:00:13 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 07:00:13 INFO - building 'psutil._psutil_posix' extension 07:00:13 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 07:00:13 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 07:00:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:13 INFO - Successfully installed psutil 07:00:13 INFO - Cleaning up... 07:00:13 INFO - Return code: 0 07:00:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:00: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 mozsystemmonitor==0.3 07:00:13 INFO - Using env: {'DISPLAY': ':0', 07:00:13 INFO - 'HOME': '/home/cltbld', 07:00:13 INFO - 'LANG': 'en_US.UTF-8', 07:00:13 INFO - 'LANGUAGE': 'en_US:en', 07:00:13 INFO - 'LOGNAME': 'cltbld', 07:00:13 INFO - 'MAIL': '/var/mail/cltbld', 07:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:13 INFO - 'MOZ_NO_REMOTE': '1', 07:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:13 INFO - 'NO_EM_RESTART': '1', 07:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:13 INFO - 'PWD': '/builds/slave/test', 07:00:13 INFO - 'SHELL': '/bin/bash', 07:00:13 INFO - 'SHLVL': '1', 07:00:13 INFO - 'TERM': 'linux', 07:00:13 INFO - 'TMOUT': '86400', 07:00:13 INFO - 'USER': 'cltbld', 07:00:13 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:13 INFO - '_': '/tools/buildbot/bin/python'} 07:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:00:15 INFO - Installing collected packages: mozsystemmonitor 07:00:15 INFO - Running setup.py install for mozsystemmonitor 07:00:15 INFO - Successfully installed mozsystemmonitor 07:00:15 INFO - Cleaning up... 07:00:15 INFO - Return code: 0 07:00:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:15 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00:15 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 07:00:15 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 07:00:15 INFO - Using env: {'DISPLAY': ':0', 07:00:15 INFO - 'HOME': '/home/cltbld', 07:00:15 INFO - 'LANG': 'en_US.UTF-8', 07:00:15 INFO - 'LANGUAGE': 'en_US:en', 07:00:15 INFO - 'LOGNAME': 'cltbld', 07:00:15 INFO - 'MAIL': '/var/mail/cltbld', 07:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:15 INFO - 'MOZ_NO_REMOTE': '1', 07:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:15 INFO - 'NO_EM_RESTART': '1', 07:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:15 INFO - 'PWD': '/builds/slave/test', 07:00:15 INFO - 'SHELL': '/bin/bash', 07:00:15 INFO - 'SHLVL': '1', 07:00:15 INFO - 'TERM': 'linux', 07:00:15 INFO - 'TMOUT': '86400', 07:00:15 INFO - 'USER': 'cltbld', 07:00:15 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:15 INFO - '_': '/tools/buildbot/bin/python'} 07:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:16 INFO - Downloading/unpacking jsonschema==2.5.1 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:00:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:18 INFO - Downloading functools32-3.2.3-2.tar.gz 07:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:18 INFO - warning: no files found matching '*.txt' 07:00:18 INFO - no previously-included directories found matching 'build' 07:00:18 INFO - no previously-included directories found matching 'dist' 07:00:18 INFO - no previously-included directories found matching '.git*' 07:00:18 INFO - Installing collected packages: jsonschema, functools32 07:00:18 INFO - Running setup.py install for functools32 07:00:18 INFO - warning: no files found matching '*.txt' 07:00:18 INFO - no previously-included directories found matching 'build' 07:00:18 INFO - no previously-included directories found matching 'dist' 07:00:18 INFO - no previously-included directories found matching '.git*' 07:00:18 INFO - Successfully installed jsonschema functools32 07:00:18 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00:18 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 07:00:18 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 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:00:18 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00:18 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 07:00:18 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 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Downloading/unpacking blobuploader==1.2.4 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 INFO - Downloading blobuploader-1.2.4.tar.gz 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:00:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:00:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 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 07:00:21 INFO - Downloading docopt-0.6.1.tar.gz 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:00:21 INFO - Installing collected packages: blobuploader, requests, docopt 07:00:21 INFO - Running setup.py install for blobuploader 07:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:00:21 INFO - Running setup.py install for requests 07:00:22 INFO - Running setup.py install for docopt 07:00:22 INFO - Successfully installed blobuploader requests docopt 07:00:22 INFO - Cleaning up... 07:00:22 INFO - Return code: 0 07:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:22 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00:22 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 07:00:22 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 07:00:22 INFO - Using env: {'DISPLAY': ':0', 07:00:22 INFO - 'HOME': '/home/cltbld', 07:00:22 INFO - 'LANG': 'en_US.UTF-8', 07:00:22 INFO - 'LANGUAGE': 'en_US:en', 07:00:22 INFO - 'LOGNAME': 'cltbld', 07:00:22 INFO - 'MAIL': '/var/mail/cltbld', 07:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:22 INFO - 'MOZ_NO_REMOTE': '1', 07:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:22 INFO - 'NO_EM_RESTART': '1', 07:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:22 INFO - 'PWD': '/builds/slave/test', 07:00:22 INFO - 'SHELL': '/bin/bash', 07:00:22 INFO - 'SHLVL': '1', 07:00:22 INFO - 'TERM': 'linux', 07:00:22 INFO - 'TMOUT': '86400', 07:00:22 INFO - 'USER': 'cltbld', 07:00:22 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:22 INFO - '_': '/tools/buildbot/bin/python'} 07:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:22 INFO - Running setup.py (path:/tmp/pip-XvJDOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:22 INFO - Running setup.py (path:/tmp/pip-Jt4I3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Running setup.py (path:/tmp/pip-QMrSh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Running setup.py (path:/tmp/pip-H8eI6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:22 INFO - Running setup.py (path:/tmp/pip-mY9RyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:23 INFO - Running setup.py (path:/tmp/pip-dv4Seo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:23 INFO - Running setup.py (path:/tmp/pip-b7Tktp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:23 INFO - Running setup.py (path:/tmp/pip-FUz0sM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:23 INFO - Running setup.py (path:/tmp/pip-NsvmQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:23 INFO - Running setup.py (path:/tmp/pip-BcYETa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:23 INFO - Running setup.py (path:/tmp/pip-XIpAkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:23 INFO - Running setup.py (path:/tmp/pip-P8l22T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:23 INFO - Running setup.py (path:/tmp/pip-2VAw9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:23 INFO - Running setup.py (path:/tmp/pip-r7cDuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:23 INFO - Running setup.py (path:/tmp/pip-ueeVWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:23 INFO - Running setup.py (path:/tmp/pip-hTu3ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Running setup.py (path:/tmp/pip-fx4DsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:00:23 INFO - Running setup.py install for manifestparser 07:00:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozcrash 07:00:24 INFO - Running setup.py install for mozdebug 07:00:24 INFO - Running setup.py install for mozdevice 07:00:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozfile 07:00:24 INFO - Running setup.py install for mozhttpd 07:00:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozinfo 07:00:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozInstall 07:00:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozleak 07:00:24 INFO - Running setup.py install for mozlog 07:00:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for moznetwork 07:00:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Running setup.py install for mozprocess 07:00:25 INFO - Running setup.py install for mozprofile 07:00:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Running setup.py install for mozrunner 07:00:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Running setup.py install for mozscreenshot 07:00:25 INFO - Running setup.py install for moztest 07:00:25 INFO - Running setup.py install for mozversion 07:00:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:00:25 INFO - Cleaning up... 07:00:25 INFO - Return code: 0 07:00:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:25 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00:25 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 07:00:25 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 07:00:25 INFO - Using env: {'DISPLAY': ':0', 07:00:25 INFO - 'HOME': '/home/cltbld', 07:00:25 INFO - 'LANG': 'en_US.UTF-8', 07:00:25 INFO - 'LANGUAGE': 'en_US:en', 07:00:25 INFO - 'LOGNAME': 'cltbld', 07:00:25 INFO - 'MAIL': '/var/mail/cltbld', 07:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:25 INFO - 'MOZ_NO_REMOTE': '1', 07:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:25 INFO - 'NO_EM_RESTART': '1', 07:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:25 INFO - 'PWD': '/builds/slave/test', 07:00:25 INFO - 'SHELL': '/bin/bash', 07:00:25 INFO - 'SHLVL': '1', 07:00:25 INFO - 'TERM': 'linux', 07:00:25 INFO - 'TMOUT': '86400', 07:00:25 INFO - 'USER': 'cltbld', 07:00:25 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:25 INFO - '_': '/tools/buildbot/bin/python'} 07:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 INFO - Running setup.py (path:/tmp/pip-XKqKKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:25 INFO - Running setup.py (path:/tmp/pip-FcOr_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:26 INFO - Running setup.py (path:/tmp/pip-XwUV1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:26 INFO - Running setup.py (path:/tmp/pip-zl18hV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:26 INFO - Running setup.py (path:/tmp/pip-eJUNnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:26 INFO - Running setup.py (path:/tmp/pip-gkfhGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:26 INFO - Running setup.py (path:/tmp/pip-r1hMa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:26 INFO - Running setup.py (path:/tmp/pip-vQ9ALV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:26 INFO - Running setup.py (path:/tmp/pip-u7f_Rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:26 INFO - Running setup.py (path:/tmp/pip-DBdisV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:26 INFO - Running setup.py (path:/tmp/pip-ruLbPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:26 INFO - Running setup.py (path:/tmp/pip-54ZkMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 INFO - Running setup.py (path:/tmp/pip-PN4hed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 INFO - Running setup.py (path:/tmp/pip-I2cjSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:26 INFO - Running setup.py (path:/tmp/pip-g4mGiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:27 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)) 07:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:27 INFO - Running setup.py (path:/tmp/pip-P_GTjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:27 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)) 07:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:27 INFO - Running setup.py (path:/tmp/pip-ujG9Gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00:27 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 07:00:27 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 07:00:27 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 07:00:27 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 07:00:27 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 07:00:27 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 07:00:29 INFO - Downloading blessings-1.6.tar.gz 07:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:00:29 INFO - Installing collected packages: blessings 07:00:29 INFO - Running setup.py install for blessings 07:00:29 INFO - Successfully installed blessings 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Current package versions: 07:00:29 INFO - argparse == 1.2.1 07:00:29 INFO - blessings == 1.6 07:00:29 INFO - blobuploader == 1.2.4 07:00:29 INFO - docopt == 0.6.1 07:00:29 INFO - functools32 == 3.2.3-2 07:00:29 INFO - jsonschema == 2.5.1 07:00:29 INFO - manifestparser == 1.1 07:00:29 INFO - mozInstall == 1.12 07:00:29 INFO - mozcrash == 0.17 07:00:29 INFO - mozdebug == 0.1 07:00:29 INFO - mozdevice == 0.48 07:00:29 INFO - mozfile == 1.2 07:00:29 INFO - mozhttpd == 0.7 07:00:29 INFO - mozinfo == 0.9 07:00:29 INFO - mozleak == 0.1 07:00:29 INFO - mozlog == 3.3 07:00:29 INFO - moznetwork == 0.27 07:00:29 INFO - mozprocess == 0.23 07:00:29 INFO - mozprofile == 0.28 07:00:29 INFO - mozrunner == 6.12 07:00:29 INFO - mozscreenshot == 0.1 07:00:29 INFO - mozsystemmonitor == 0.3 07:00:29 INFO - moztest == 0.7 07:00:29 INFO - mozversion == 1.4 07:00:29 INFO - psutil == 3.1.1 07:00:29 INFO - requests == 1.2.3 07:00:29 INFO - wsgiref == 0.1.2 07:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00:29 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 07:00:29 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 07:00:29 INFO - Using env: {'DISPLAY': ':0', 07:00:29 INFO - 'HOME': '/home/cltbld', 07:00:29 INFO - 'LANG': 'en_US.UTF-8', 07:00:29 INFO - 'LANGUAGE': 'en_US:en', 07:00:29 INFO - 'LOGNAME': 'cltbld', 07:00:29 INFO - 'MAIL': '/var/mail/cltbld', 07:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:29 INFO - 'MOZ_NO_REMOTE': '1', 07:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:29 INFO - 'NO_EM_RESTART': '1', 07:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:29 INFO - 'PWD': '/builds/slave/test', 07:00:29 INFO - 'SHELL': '/bin/bash', 07:00:29 INFO - 'SHLVL': '1', 07:00:29 INFO - 'TERM': 'linux', 07:00:29 INFO - 'TMOUT': '86400', 07:00:29 INFO - 'USER': 'cltbld', 07:00:29 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:29 INFO - '_': '/tools/buildbot/bin/python'} 07:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 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)) 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', '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 07:00:29 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 07:00:29 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 07:00:29 INFO - Using env: {'DISPLAY': ':0', 07:00:29 INFO - 'HOME': '/home/cltbld', 07:00:29 INFO - 'LANG': 'en_US.UTF-8', 07:00:29 INFO - 'LANGUAGE': 'en_US:en', 07:00:29 INFO - 'LOGNAME': 'cltbld', 07:00:29 INFO - 'MAIL': '/var/mail/cltbld', 07:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:29 INFO - 'MOZ_NO_REMOTE': '1', 07:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:29 INFO - 'NO_EM_RESTART': '1', 07:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:29 INFO - 'PWD': '/builds/slave/test', 07:00:29 INFO - 'SHELL': '/bin/bash', 07:00:29 INFO - 'SHLVL': '1', 07:00:29 INFO - 'TERM': 'linux', 07:00:29 INFO - 'TMOUT': '86400', 07:00:29 INFO - 'USER': 'cltbld', 07:00:29 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:29 INFO - '_': '/tools/buildbot/bin/python'} 07:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Running post-action listener: _resource_record_post_action 07:00:29 INFO - Running post-action listener: _start_resource_monitoring 07:00:29 INFO - Starting resource monitoring. 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.990736Z] Finished create-virtualenv step (success) 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.991652Z] Running install step. 07:00:29 INFO - Running pre-action listener: _resource_record_pre_action 07:00:29 INFO - Running main action method: install 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:30 INFO - Reading from file tmpfile_stdout 07:00:30 INFO - Detecting whether we're running mozinstall >=1.0... 07:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:00:30 INFO - Reading from file tmpfile_stdout 07:00:30 INFO - Output received: 07:00:30 INFO - Usage: mozinstall [options] installer 07:00:30 INFO - Options: 07:00:30 INFO - -h, --help show this help message and exit 07:00:30 INFO - -d DEST, --destination=DEST 07:00:30 INFO - Directory to install application into. [default: 07:00:30 INFO - "/builds/slave/test"] 07:00:30 INFO - --app=APP Application being installed. [default: firefox] 07:00:30 INFO - mkdir: /builds/slave/test/build/application 07:00:30 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'] 07:00:30 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 07:00:46 INFO - Reading from file tmpfile_stdout 07:00:46 INFO - Output received: 07:00:46 INFO - /builds/slave/test/build/application/firefox/firefox 07:00:46 INFO - Running post-action listener: _resource_record_post_action 07:00:46 INFO - [mozharness: 2016-09-01 14:00:46.305126Z] Finished install step (success) 07:00:46 INFO - [mozharness: 2016-09-01 14:00:46.305398Z] Running run-tests step. 07:00:46 INFO - Running pre-action listener: _resource_record_pre_action 07:00:46 INFO - Running main action method: run_tests 07:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:46 INFO - Python 2.7.3 07:00:46 INFO - Return code: 0 07:00:46 INFO - grabbing minidump binary from tooltool 07:00:46 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')]} 07:00:46 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 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00:46 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 07:00:46 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 07:00:46 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 07:00:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:46 INFO - Return code: 0 07:00:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:00:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:00:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:46 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/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 07:00:46 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/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 07:00:46 INFO - Using env: {'DISPLAY': ':0', 07:00:46 INFO - 'HOME': '/home/cltbld', 07:00:46 INFO - 'LANG': 'en_US.UTF-8', 07:00:46 INFO - 'LANGUAGE': 'en_US:en', 07:00:46 INFO - 'LOGNAME': 'cltbld', 07:00:46 INFO - 'MAIL': '/var/mail/cltbld', 07:00:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:46 INFO - 'MOZ_NO_REMOTE': '1', 07:00:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:46 INFO - 'NO_EM_RESTART': '1', 07:00:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:46 INFO - 'PWD': '/builds/slave/test', 07:00:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:46 INFO - 'SHELL': '/bin/bash', 07:00:46 INFO - 'SHLVL': '1', 07:00:46 INFO - 'TERM': 'linux', 07:00:46 INFO - 'TMOUT': '86400', 07:00:46 INFO - 'USER': 'cltbld', 07:00:46 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688', 07:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:46 INFO - '_': '/tools/buildbot/bin/python'} 07:00:46 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/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 07:00:46 INFO - mozversion application_buildid: 20160830093921 07:00:46 INFO - mozversion application_changeset: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 07:00:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:00:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:46 INFO - mozversion application_name: Firefox 07:00:46 INFO - mozversion application_remotingname: firefox-dev 07:00:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion application_vendor: Mozilla 07:00:46 INFO - mozversion application_version: 50.0a2 07:00:46 INFO - mozversion platform_buildid: 20160830093921 07:00:46 INFO - mozversion platform_changeset: c819e284a13937fc955c2efb6da7fdf9c5bb8b88 07:00:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion platform_version: 50.0a2 07:00:46 INFO - using testdate: 1472738446 07:00:46 INFO - actual date: 1472738446 07:00:46 INFO - starting webserver on 'localhost:57728' 07:00:46 INFO - SUITE-START | Running 2 tests 07:00:46 INFO - TEST-START | dromaeo_css 07:00:46 INFO - operating with platform_type : linux_ 07:00:46 INFO - Initialising browser for dromaeo_css test... 07:00:46 INFO - TEST-INFO | started process 3354 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSWu3Xj/profile http://localhost:57728/getInfo.html) 07:00:54 INFO - PROCESS | 3354 | __metrics Screen width/height:1600/1200 07:00:54 INFO - PROCESS | 3354 | colorDepth:24 07:00:54 INFO - PROCESS | 3354 | Browser inner width/height: 1024/697 07:00:54 INFO - PROCESS | 3354 | __metrics 07:00:55 INFO - PROCESS | 3354 | 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" 07:00:55 INFO - PROCESS | 3354 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:00:56 INFO - TEST-INFO | 3354: exit 0 07:00:56 INFO - Browser initialized. 07:00:56 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:56 INFO - TEST-INFO | started process 3443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSWu3Xj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:07 INFO - PROCESS | 3443 | RSS: Main: 145121280 07:01:07 INFO - PROCESS | 3443 | 07:01:58 INFO - PROCESS | 3443 | 1472738518953 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] 07:04:08 INFO - PROCESS | 3443 | Cycle 1(1): loaded http://localhost:57728/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57728/tests/dromaeo/cssquery-ext.html) 07:04:09 INFO - PROCESS | 3443 | RSS: Main: 272551936 07:04:09 INFO - PROCESS | 3443 | 07:07:12 INFO - PROCESS | 3443 | Cycle 1(1): loaded http://localhost:57728/tests/dromaeo/cssquery-ext.html (next: http://localhost:57728/tests/dromaeo/cssquery-jquery.html) 07:07:12 INFO - PROCESS | 3443 | RSS: Main: 232611840 07:07:12 INFO - PROCESS | 3443 | 07:10:14 INFO - PROCESS | 3443 | Cycle 1(1): loaded http://localhost:57728/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57728/tests/dromaeo/cssquery-mootools.html) 07:10:15 INFO - PROCESS | 3443 | RSS: Main: 175525888 07:10:15 INFO - PROCESS | 3443 | 07:13:17 INFO - PROCESS | 3443 | Cycle 1(1): loaded http://localhost:57728/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57728/tests/dromaeo/cssquery-prototype.html) 07:13:17 INFO - PROCESS | 3443 | RSS: Main: 176840704 07:13:17 INFO - PROCESS | 3443 | 07:16:20 INFO - PROCESS | 3443 | Cycle 1(1): loaded http://localhost:57728/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57728/tests/dromaeo/cssquery-yui.html) 07:16:21 INFO - PROCESS | 3443 | RSS: Main: 176353280 07:16:21 INFO - PROCESS | 3443 | 07:19:23 INFO - PROCESS | 3443 | Cycle 1(1): loaded http://localhost:57728/tests/dromaeo/cssquery-yui.html (next: http://localhost:57728/tests/dromaeo/cssquery-dojo.html) 07:19:23 INFO - PROCESS | 3443 | RSS: Main: 245035008 07:19:23 INFO - PROCESS | 3443 | 07:19:23 INFO - PROCESS | 3443 | __start_tp_report 07:19:23 INFO - PROCESS | 3443 | _x_x_mozilla_page_load 07:19:23 INFO - PROCESS | 3443 | _x_x_mozilla_page_load_details 07:19:23 INFO - PROCESS | 3443 | |i|pagename|runs| 07:19:23 INFO - PROCESS | 3443 | |0;dojo.html;3272.590926134233;3288.6053673559177;3306.991367632872;3312.7846689965168;3336.5328853960436;5663.886722265553;5740.483356497914;5744.569157313197;5762.827115186551;5786.913196302059;5746.454086861752;5784.1902133701315;5802.709864506779;5804.564657650682;5815.127730840376;5085.644004919657;5139.25480805283;5232.895342093156;5292.629515933886;5328.227407025979;5167.586593072564;5179.559737422323;5190.221466779975;5236.659617124894;5247.895042099156;5180.352455943007;5201.2458193561915;5263.3683957925105;5265.052290587692;5283.735813209343;5327.573794096502;5339.999999999981;5341.919871201935;5343.278657381243;5348.946510534884;5083.135866902636;5139.563137133348;5178.171492561172;5241.449647786965;5250.553702935255;5604.607677462579;5641.746121424505;5661.886762264773;5666.801661941853;5680.971595142039;6079.300880398722;6088.817335479943;6089.662210937712;6171.907421388682;6190.41191086846;5906.911396329058;6128.687451924525;6140.048292514667;6160.983437732769;6196.0705894116245;5846.7076646167525;5851.502622277111;5861.6189947653265;5865.325487568899;5870.7945221917025;6387.840303992344;6390.201224846895;6391.520635952229;6398.9040164397575;6398.936010639927;5855.443732845373;5989.670568118795;6040.275166979903;6048.516118710492;6116.66358350295;6210.658413787195;6211.782587609502;6212.968935155295;6221.720022598994;6223.657698826518;6132.41742034506;6158.445739883432;6165.445109940127;6173.536984726164;6178.474829639531;5981.282246130492;6049.909251361233;6107.0228763397645;6113.449789518964;6171.105189747462;4669.743164125938;4700.905981880412;4716.316134160596;4718.693284936468;4720.95279047212;4161.0845613964875;4179.247735407595;4182.728122672017;4196.454460920061;4212.094399704079;5663.490285874291;5665.745041473144;5700.971495142498;5701.914471282934;5788.363280039192;6137.171481849982;6168.19813424252;6185.350538193425;6190.102435146969;6197.070439434031;5788.797392091341;5796.3913789052385;5802.187693722883;5805.332386775575;5811.564132690065;5210.088234558937;5235.790568377222;5291.153415453509;5292.92060619091;5365.222042803772;6329.683515824098;6352.174217351715;6367.458766004941;6369;6369.394907483782;6136.7852125175405;6140.877182456236;6198.845028874314;6210.596311239755;6211.565190436626;6135.110408990582;6145.893739127;6147.047207682816;6245.281792593728;6308.432241098323;6073.848153796191;6126.24286675468;6133.946794548733;6138.357744607992;6142.805741014737;6197.225346831646;6198.752049917953;6200.534959877937;6208.255009398901;6238.095476155841;5897.262842144732;5996.070609055603;6052.66710330936;6106.175666284995;6112.052631842072;5903.203067585821;6032.33644299118;6051.909221361595;6064.848378790565;6069.908951365821;5996.580239383201;6003.249593800775;6005.159277701038;6005.669688167192;6011.248593925759;5839.970800145972;5978.312494063064;6051.3041000283765;6079.160724792212;6088.665123418255;5894.174815525744;5951.137085122719;5975.133605627247;5998.430149135826;6002.579819412599 07:19:23 INFO - PROCESS | 3443 | |1;ext.html;583.4224118123057;599.9940000599994;620.8354785981716;629.0532748213939;643.7489379142138;35051.2989740205;37875.242495150116;37940.24119517612;46968;48195;47894;47981;48463;48505.27242091363;48665;9271.35100542964;9427.151556359926;9431.622735090623;9435.905640943605;9439.764005899839;8642.092580279075;11042.116630669567;11323.094152467824;11330.490127944262;11629.24409913353;11329.433528323592;11417.086633069375;11418.885811141865;11419.828702569468;11424.942875285611;8584.141585841366;11248.100151987821;11296.887031129665;11317;11327.150463715254;10759.83860242097;11130.05394541465;11148.944255278671;11450.599229026944;11460.083193344595;46159.07681846378;46526.76736616295;46563;46608.30087548689;46619.76690116562;3757.4551690004796;3898.4152377143655;4054.635082842529;4194.622483976456;4298;35317.64682353169;35410.822945885106;35439;35444.468332975026;35508.822455887814;4523.547645235451;4537.31940208968;4570.954290457086;4607.585317321424;4608.769561521911;393105;394388;396340;396613;397121;63686.36313636897;64101;64168;64224.67887660532;64312.35687643157;39871.601283987366;40974.1805163901;41104.38343424851;43319;68721.65639171773;18828.529286767665;18899.338523151622;18941.242350305834;18950.24199032051;18962.620747585246;12307.75384492305;14959.177245251398;15130.773038404433;15232.162231077176;15241.1617361044;19861.106250218774;20773.169073236902;20861.791382085983;20862.687059694115;21020.15919363239;7365.5693621105975;7607.239276072349;7635.618219089023;7664.4634875559295;7858.174891636296;1042.5412818360048;1044.968650940473;1054.3568423261804;1078.687180717583;1145.3585991844593;34475.82762086173;35626.643733562334;35629.28741425157;35644.46533302002;35648.821755891055;1159.8956093951585;1171.37916904041;1210.3222195570509;1210.5278941212935;1230.6615680687883;18343.32202444125;18385.172667230006;18396.724049139273;18472.630547388977;18611.25554977813;624824;629339;629862;632390.0282991518;633471;66045.66977165084;66550;66570.33429665641;66654.33345666481;66661;1300.6618279247464;1404.1996062244627;1427.7215942891037;1443.0475885915246;1446.8408475067524;34788.478172827934;36449.817750911076;36489.45265821068;36651.816740917195;36670.8166459166;54858.45141548533;55561.722191388784;55563.72218138883;55810;55840.44159558352;420077;421016;421393;422105;422158;28768.712312876603;28966.855165724035;29419;31482.52776208313;33919.491207631414;52714;53893;54036.45963540472;54402.727986359816;55730.44269557253;44377.33433998557;45258.32112518381;45507.04596666296;45607.31589026233;46282;44406;45510.772446137555;45953.77023114863;46436;46895.531044689116;44655.776721116184;44766.55233447624;44788.77605611951;44904.32643510285;44925.55074449214;38039.80980095192;39242;39909.401358979056;40287.79856100701;40510.797446012584;29850;30045;30229;30232.244193895327;30290.394192116473 07:19:23 INFO - PROCESS | 3443 | |2;jquery.html;395.34570286176387;416.8947055324212;432.9523752387238;437.3068686132478;439.32344189947474;17166.3133474661;20521.179152833887;21931.12275508982;21983.340499785012;22712.54574908501;20714.689279660815;20768;21135.471613209676;21348.8932555337;21374;5852.502537284326;5936.109583562478;6010.218671572689;6022.397760223965;6022.638641681491;5882.970585147068;5939.940600593982;5940.257467816523;5941.079132734433;5944.464998150165;5892.499137573312;5912.3496415394275;5924.6445213287125;5940.990031694622;5984.97007514964;5840;5960.7019649017375;5991.730372133265;5995.130706047642;5996.22049133612;5214.504622060898;5335;5346.652467589594;5385.111456609655;5386.030514507387;25492.490150197078;25492.74507254941;25536;25542.361440964123;25668.614970775452;2608.543504886656;2647.9867600662064;2681.7988650851266;3182.5385319128827;3342.9498557521656;15610.687786244325;15839.683206335809;15847.366105355772;15866.682666346609;15893.046417214888;2079.4593405714472;2088.4883203615213;2105.768365479796;2117.95764084719;2121.2045482943895;87622.5618871908;88663;89181.10818891857;90540.54729726375;91577;9929.851052234222;10619.575216991389;11171.944140279247;11172.10623150146;12248.510059597695;16019.839801602067;16569.25438355277;16655.750163747554;16755.497335079723;16764.41324553659;9157.130072643089;9166.17504424605;9175.862362064574;9332.58003389849;9397.76505587352;8924;8997.145271199226;9013;9208.12522810332;9212;7057.50597458173;7099.219085900547;7151.463640227003;7217.386522145676;7241.4206863451855;2011.9698204526944;2028.3914825552276;2068.4001639524367;2084.1663334666255;2142.1752625239396;984.675057231112;1022.2946167144646;1024.1141412678128;1058.3385384134915;1077.1221454514546;21223.68164477534;21756.564868702855;22092.668609970864;22237.332880013626;22261.332160035225;1080.475969154959;1094.5129417409335;1107.5182295701395;1108.800415925125;1123.8932301431514;12331.321777302335;12578.68553286157;12600.369981501071;12682.809757853454;12748.107632465639;573325.8199480387;575151;587081;597436;599696;89543;92301.53849230711;92596;93508.53245733728;94185.5290723542;1065.1106326217541;1134.7787181499693;1136.7669627726461;1159.6289187459931;1161.7211869151342;18112.81887181164;18560.257589696263;18955.43133705936;18959.052047397297;19447.222111115396;31845.681543185197;41214.175716484904;41447.9276036203;43243.78378108089;44487.55512444834;61934.38065619466;62282;62338.376616233254;62364;64330.67834660984;8535.8719619207;8549.487030778173;8670.26302764272;9058.184763371328;9073.319501037317;12419.254844709347;14004.36980335867;14028.018038737599;14114;14172.29138543048;11518.481668325114;11838.940805295919;12227.388630568257;12237.082218833444;12788.680282993;12192.817107743569;12361;12372.443240054039;12398.380080995734;12545.372731363577;12226.755464890475;12446.190997585129;12457.813132803196;12582.245065295745;12680.175788573713;8670;8816.206541411048;8985.685501007694;8996.325275604488;9083.68207112748;11563.42182890869;11984.820227696417;12016.879831201926;12138.150329477205;12434.253944763343 07:19:23 INFO - PROCESS | 3443 | |3;mootools.html;1534.8618624323813;1590.3082159260723;1753.149722384644;1758.3845654021086;1887.6602211601905;9064.864027039592;9751.122398984082;9756.414615123098;9784.804303913917;9787.070228328315;8359.247667709906;9061.365704400694;9332.720018399443;9342;9520.809583808319;2515.6478093066985;2542.745725427461;2544.5547029269896;2545.6563363945816;2555.8083143764293;2448.8285819992607;2582.044643481914;2582.3673200065914;2587.9870600647064;2588.5470042742445;2526.1916186820226;2537.961930571043;2578.252306831026;2579.3035880312304;2582.7288134745863;2493.7506249375097;2499.950000999988;2502.5620516409554;2517.1190083470824;2521.7730404263702;2303.159346838392;2303.1823702585507;2313.5257272259146;2376.9881150594138;2490.57660197765;9634.229261659051;9811.656592019317;9825.705228843075;9833.655822046194;9840;5567.164925261202;5666.376698563155;5722.656640601577;5731.254936858223;5733.426657334275;3631.909202269938;3749.793761343125;3806.105565192205;3825.885223443301;3826.330392181357;1199.1426130316866;1279.2772083772638;1282.647272000198;1284.601773450224;1298.4611386274685;13172.60482185536;13511.391987360494;13539;13816.516421925184;13899.235542045184;4869.756512174377;4889.3888263967;4931.950680493149;5001.649884508122;5049.091163590516;10842.241043126905;10851.837222441669;10854.837177442345;10873.945630271797;10882.238243322892;5685.744141513642;5688.374278829326;5689.630174038674;5691.630044047207;5693.629914055573;4656.348111264426;5135.87160320995;5209.453007434241;5255.237990491358;5297.735113244323;4046.5346485153996;4048.2308361411237;4099.344104943195;4141.81361838718;4180.728252663628;1215.155466950461;1219.8353222314875;1220.8718084601167;1240.7456471423336;1251.305525433375;960.279790157382;1022.928395012342;1051.8842927277992;1059.0838924330399;1068.780899915531;6255.186825712628;6687.933120668828;6699.330066993261;6819.24988251292;6837.213720422215;982.2387649571497;997.5411310796969;998.960041598328;1012.175077312003;1029.5264178477985;4421.624161946206;4592.931106033412;4628.4445866496235;4744.074905393415;4751.073540659539;11593.24643898144;12215.572454964034;12687.492500299884;12708.555200567936;12739.61781146567;12024.158308918291;12406.689832754073;12428.813567796491;12438.937805310916;12446.25322480654;8970.506622135714;9135.360524763202;9138.68014619485;9160.6793762218;9185.540722963826;6603.339666033358;6784.660766961731;6829.4877884157895;6896.206936202401;6922.203946546211;11134.33194008362;11502.367369794742;11523.13576481784;11564.074874010228;11578.71053223676;11430.314181149157;11467.770644587228;11654.067674585844;11730.589429370273;11757.588484403004;8781.077986811548;8877.822443551222;8885.378023538291;8936.374453788432;8990.86513702308;9189.954050229973;9228.584713688035;9280.025597312186;9305.581248843682;9306.162445379949;8511.276541494042;8565.700200492953;8630.352723545633;8679.566021698764;8693.608787604446;8243.25810677042;8334.541600211804;8350.62422191015;8400.117987611213;8421.831563368822;7491.70033198666;7497.887531687138;7585.848283034419;7586.089669239726;7586.734464293723;5738.856528586819;5777.24895763548;5827.9417205829095;5836.066229403275;6041.426064523863;5817.360090390139;5831.1253312001845;5891.204687367151;5932.911006334822;6057.42454466825 07:19:23 INFO - PROCESS | 3443 | |4;prototype.html;362.17243598377706;362.7859562857913;364.2623687033756;373.9644733750293;377.6525596451265;5568.6658800472;5581;5611.551075913932;5631.943680563193;5644.915326270109;5279.788808447657;5486.204500347447;5537.58468114891;5553.888922221553;5562.193481945115;4440.467143942716;4447.0661161156195;4453.131639330332;4456.910861782762;4470.441194850644;4433.157700036999;4437.977810110945;4439.933400998972;4451.465824101112;4472.21736196166;4169.76991787422;4248.83004679814;4390.341448782676;4418.889527761799;4424.181526417607;4344.567042456219;4378.562143785628;4378.671599630009;4381.9561804381865;4395.406620106292;3908.4137379393032;3920.0595705126143;3922.921541569181;3936.153726948691;3945.5462621798574;5193.376794784613;5290.3599884036075;5426.430224826376;5450.811275403894;5458.099413596752;5013.749312534395;5024.899502009976;5058.570021548136;5063.949360506384;5069.44236134025;5799.550112471882;5801.24583804107;5831.154482600042;5866.6480011199465;5924.763009479616;4142.502899652061;4274.102438487921;4300.35494675798;4312.374705667692;4340.066885619575;6037.154798328237;6075.513958883279;6086.421789929949;6091.847703807396;6094.177286066369;4349.13017396522;4373.453318335208;4387.583179597933;4394;4399.934000989988;4302.204092242882;4490.146872094291;4511.932321015187;4520.954790452053;4545.772711364419;5061.4179369373;5077.771500282496;5090.872728181825;5098.413682426494;5144.202648589399;5633.8591535211945;5894.38108998549;5896.174535565025;5935.762569497172;5936.821895343147;1550.8139023317274;1578.6684796192928;1581.2172974377756;1582.6122599962853;1586.4050980882168;1020.336781092296;1024.134606257711;1041.193075366597;1046.1892033673812;1050.826613608746;5028.572571331478;5042.974785126051;5076.162433198555;5122.769475373542;5129.922381435525;5338.546223570962;5344.412114667383;5377.838664840061;5391.487808658171;5412.134058550613;5134.358205224347;5224.399194092652;5506.339239291311;5696.971515142398;5704.372519022904;3144.2768163322107;3173.222560472699;3173.6826317368077;3178.1577881861326;3185.7699610272903;5940.495057920125;6116.510679145657;6118;6200.0699895015205;6202.82146392184;6033.306169790442;6037.335893051761;6043.335233124353;6060.575759696779;6073.48375388097;6119.541034422347;6122.908156377658;6138.294096178818;6139.508839292846;6144.8771024579255;5792.94207057924;5926.881462370815;5941.435563621449;5973.432523910222;5992.250968628921;5844.94155058461;5864.618799778001;5877.326670830731;5879.412058794086;5900.940990590039;5953.434423729739;6100;6128.693565321627;6137.570370074052;6170.598911070766;4384.254676704904;4389.410247953722;4411.580899814512;4416.271315232951;4418.933715994199;5934.1988831508925;5939.643621382731;5951.851203719942;5989.191459153133;5994.28068631767;5584.776608935597;5842.1820945068575;5847.970760146342;5870.677112758668;5906.114082887601;5816.941830581809;5830.679312637845;5845.766169353178;5846.093855452412;5861.3845546217035;5470.480304371078;5553.722313884209;5820.447057529528;5835.824925252249;5839.182514448065;5855.092460668603;5865;5885.735141918538;5888.764449422147;5894.145348924468;5718.340971448297;5798.797042103675;5804.477597016289;5816.73824677882;5817.621854579439 07:19:23 INFO - PROCESS | 3443 | |5;yui.html;817.705625974649;828.2545708862024;851.0595791650227;864.8875646165998;887.9289656827455;16053.678926421479;17184.91407542962;17217.13914304283;17340.56648583786;17343;14130.434782608683;14702.485413010547;15773.290201940912;15895.761563576556;15922.76115858263;738.1400668221515;875.1248751248767;891.6121487153076;892.5448021509045;893.8167675626481;858.1975852577859;878.7583414561014;887.8579427291602;890.9599068041921;892.7812685891965;946.4699768129835;947.4741518457254;947.6114792934898;948.1182500274741;948.7106432538073;755.0599503618031;783.4046124945082;937.6202637931615;937.6624415210518;937.9812403751921;579.0879365000142;650.8275307043638;677.014943257564;680.7413182990432;681.6659836680041;16138.596535086599;16175.19124043793;16241.83758162427;16249.756253656205;16279.83720162795;14865.405383784744;15369.154696491689;15898.443554475538;15947.681046379124;16035.037897726173;13337.666558336023;13452.5291614793;13463.932680336633;13482.191068535822;13607.863921360859;4536.500984891659;4590.127875703639;4632.2125238709505;4653.813847446098;4755.096531659008;85190.57404712937;92642;92670;93063;93562.99999999932;11684.298942063504;11754.823677644843;11790;12389.876101239053;12754.04344674153;21976.560468790536;22139.667904981437;22225.666615000788;22277.108915643516;22284.44288892758;8674.86987695185;8774.912250877538;8862.911370886337;8966.775830604156;9204.907950920538;4806.423229212517;4901.362822833009;5000.574951129122;5128.512791284822;5181.222816577543;4005.2590270799997;4213.073123912734;4221.345691417835;4221.430106935586;4221.472315960505;714.1537278325201;729.0631538473143;755.0976582983392;777.941654375924;779.0806847919396;688.6935313785417;725.1262229014026;761.2615762710162;763.1910175214291;796.5419883566972;10955.83566246507;10992.670219893416;11005.889941100646;11137.387443690675;11164.665060048212;663.7477758451854;703.6270776488469;745.3776096958992;797.4338219863962;821.4947807098591;4981.2030075187795;5286.577073834084;5319.46805319465;5342.5993050521365;5369.40936496985;115790.42104789423;117258;118369;120983.39508302402;123151;86285.568572158;101824.49087754513;105934.47032764787;106188;107851.46074269735;88361;88750;93144;94025;95584.52207738916;10660;10729.9463502682;11151.944240278746;11162.720931976766;11183.72040698989;47027;52687.473125268254;53827.730861345444;54026.72986635042;54210;45883.611424915754;52911.73544132255;54075.72962135164;54334.184987224435;55603.443965559825;13273.668158295734;13652.65868353299;13792.310384480936;14050.578482645536;14264.857351426353;15457;15897;15923.363065477251;16547.917260413622;16649.41727039548;12311.322877241837;13805.79291310611;14550.854491455373;15110.924445377703;15306.464273750365;14681.48614798477;15087.133444645482;15129.773053403987;15151.848481515484;15610.141442220787;14829.332680029209;15014.924925375302;15064.774028389802;15304.923475382997;15414.383424662889;10123.291369604058;10590.629327973484;10665.573377064831;10736.624218152327;10892.353111317185;12024.158308918291;12145.817812732992;12474.75050499003;12581.685457863627;12698.174618649758 07:19:23 INFO - PROCESS | 3443 | __end_tp_report 07:19:23 INFO - PROCESS | 3443 | __start_cc_report 07:19:23 INFO - PROCESS | 3443 | _x_x_mozilla_cycle_collect,1115 07:19:23 INFO - PROCESS | 3443 | __end_cc_report 07:19:23 INFO - PROCESS | 3443 | __startTimestamp1472739563925__endTimestamp 07:19:23 INFO - PROCESS | 3443 | 07:19:23 INFO - PROCESS | 3443 | ------- Summary: start ------- 07:19:23 INFO - PROCESS | 3443 | Number of tests: 6 07:19:23 INFO - PROCESS | 3443 | 07:19:23 INFO - PROCESS | 3443 | [#0] dojo.html Cycles:165 Average:5714.20 Median:5975.13 stddev:653.51 (10.9%) stddev-sans-first:626.82 07:19:23 INFO - PROCESS | 3443 | Values: 3272.6 3288.6 3307.0 3312.8 3336.5 5663.9 5740.5 5744.6 5762.8 5786.9 5746.5 5784.2 5802.7 5804.6 5815.1 5085.6 5139.3 5232.9 5292.6 5328.2 5167.6 5179.6 5190.2 5236.7 5247.9 5180.4 5201.2 5263.4 5265.1 5283.7 5327.6 5340.0 5341.9 5343.3 5348.9 5083.1 5139.6 5178.2 5241.4 5250.6 5604.6 5641.7 5661.9 5666.8 5681.0 6079.3 6088.8 6089.7 6171.9 6190.4 5906.9 6128.7 6140.0 6161.0 6196.1 5846.7 5851.5 5861.6 5865.3 5870.8 6387.8 6390.2 6391.5 6398.9 6398.9 5855.4 5989.7 6040.3 6048.5 6116.7 6210.7 6211.8 6213.0 6221.7 6223.7 6132.4 6158.4 6165.4 6173.5 6178.5 5981.3 6049.9 6107.0 6113.4 6171.1 4669.7 4700.9 4716.3 4718.7 4721.0 4161.1 4179.2 4182.7 4196.5 4212.1 5663.5 5665.7 5701.0 5701.9 5788.4 6137.2 6168.2 6185.4 6190.1 6197.1 5788.8 5796.4 5802.2 5805.3 5811.6 5210.1 5235.8 5291.2 5292.9 5365.2 6329.7 6352.2 6367.5 6369.0 6369.4 6136.8 6140.9 6198.8 6210.6 6211.6 6135.1 6145.9 6147.0 6245.3 6308.4 6073.8 6126.2 6133.9 6138.4 6142.8 6197.2 6198.8 6200.5 6208.3 6238.1 5897.3 5996.1 6052.7 6106.2 6112.1 5903.2 6032.3 6051.9 6064.8 6069.9 5996.6 6003.2 6005.2 6005.7 6011.2 5840.0 5978.3 6051.3 6079.2 6088.7 5894.2 5951.1 5975.1 5998.4 6002.6 07:19:23 INFO - PROCESS | 3443 | 07:19:23 INFO - PROCESS | 3443 | [#1] ext.html Cycles:180 Average:65553.52 Median:39890.50 stddev:131030.51 (328.5%) stddev-sans-first:131307.28 07:19:23 INFO - PROCESS | 3443 | Values: 583.4 600.0 620.8 629.1 643.7 35051.3 37875.2 37940.2 46968.0 48195.0 47894.0 47981.0 48463.0 48505.3 48665.0 9271.4 9427.2 9431.6 9435.9 9439.8 8642.1 11042.1 11323.1 11330.5 11629.2 11329.4 11417.1 11418.9 11419.8 11424.9 8584.1 11248.1 11296.9 11317.0 11327.2 10759.8 11130.1 11148.9 11450.6 11460.1 46159.1 46526.8 46563.0 46608.3 46619.8 3757.5 3898.4 4054.6 4194.6 4298.0 35317.6 35410.8 35439.0 35444.5 35508.8 4523.5 4537.3 4571.0 4607.6 4608.8 393105.0 394388.0 396340.0 396613.0 397121.0 63686.4 64101.0 64168.0 64224.7 64312.4 39871.6 40974.2 41104.4 43319.0 68721.7 18828.5 18899.3 18941.2 18950.2 18962.6 12307.8 14959.2 15130.8 15232.2 15241.2 19861.1 20773.2 20861.8 20862.7 21020.2 7365.6 7607.2 7635.6 7664.5 7858.2 1042.5 1045.0 1054.4 1078.7 1145.4 34475.8 35626.6 35629.3 35644.5 35648.8 1159.9 1171.4 1210.3 1210.5 1230.7 18343.3 18385.2 18396.7 18472.6 18611.3 624824.0 629339.0 629862.0 632390.0 633471.0 66045.7 66550.0 66570.3 66654.3 66661.0 1300.7 1404.2 1427.7 1443.0 1446.8 34788.5 36449.8 36489.5 36651.8 36670.8 54858.5 55561.7 55563.7 55810.0 55840.4 420077.0 421016.0 421393.0 422105.0 422158.0 28768.7 28966.9 29419.0 31482.5 33919.5 52714.0 53893.0 54036.5 54402.7 55730.4 44377.3 45258.3 45507.0 45607.3 46282.0 44406.0 45510.8 45953.8 46436.0 46895.5 44655.8 44766.6 44788.8 44904.3 44925.6 38039.8 39242.0 39909.4 40287.8 40510.8 29850.0 30045.0 30229.0 30232.2 30290.4 07:19:23 INFO - PROCESS | 3443 | 07:19:23 INFO - PROCESS | 3443 | [#2] jquery.html Cycles:180 Average:32820.60 Median:25539.18 stddev:96306.17 (377.1%) stddev-sans-first:96545.56 07:19:23 INFO - PROCESS | 3443 | Values: 395.3 416.9 433.0 437.3 439.3 17166.3 20521.2 21931.1 21983.3 22712.5 20714.7 20768.0 21135.5 21348.9 21374.0 5852.5 5936.1 6010.2 6022.4 6022.6 5883.0 5939.9 5940.3 5941.1 5944.5 5892.5 5912.3 5924.6 5941.0 5985.0 5840.0 5960.7 5991.7 5995.1 5996.2 5214.5 5335.0 5346.7 5385.1 5386.0 25492.5 25492.7 25536.0 25542.4 25668.6 2608.5 2648.0 2681.8 3182.5 3342.9 15610.7 15839.7 15847.4 15866.7 15893.0 2079.5 2088.5 2105.8 2118.0 2121.2 87622.6 88663.0 89181.1 90540.5 91577.0 9929.9 10619.6 11171.9 11172.1 12248.5 16019.8 16569.3 16655.8 16755.5 16764.4 9157.1 9166.2 9175.9 9332.6 9397.8 8924.0 8997.1 9013.0 9208.1 9212.0 7057.5 7099.2 7151.5 7217.4 7241.4 2012.0 2028.4 2068.4 2084.2 2142.2 984.7 1022.3 1024.1 1058.3 1077.1 21223.7 21756.6 22092.7 22237.3 22261.3 1080.5 1094.5 1107.5 1108.8 1123.9 12331.3 12578.7 12600.4 12682.8 12748.1 573325.8 575151.0 587081.0 597436.0 599696.0 89543.0 92301.5 92596.0 93508.5 94185.5 1065.1 1134.8 1136.8 1159.6 1161.7 18112.8 18560.3 18955.4 18959.1 19447.2 31845.7 41214.2 41447.9 43243.8 44487.6 61934.4 62282.0 62338.4 62364.0 64330.7 8535.9 8549.5 8670.3 9058.2 9073.3 12419.3 14004.4 14028.0 14114.0 14172.3 11518.5 11838.9 12227.4 12237.1 12788.7 12192.8 12361.0 12372.4 12398.4 12545.4 12226.8 12446.2 12457.8 12582.2 12680.2 8670.0 8816.2 8985.7 8996.3 9083.7 11563.4 11984.8 12016.9 12138.2 12434.3 07:19:23 INFO - PROCESS | 3443 | 07:19:23 INFO - PROCESS | 3443 | [#3] mootools.html Cycles:180 Average:6402.79 Median:4747.57 stddev:3689.62 (77.7%) stddev-sans-first:3681.83 07:19:23 INFO - PROCESS | 3443 | Values: 1534.9 1590.3 1753.1 1758.4 1887.7 9064.9 9751.1 9756.4 9784.8 9787.1 8359.2 9061.4 9332.7 9342.0 9520.8 2515.6 2542.7 2544.6 2545.7 2555.8 2448.8 2582.0 2582.4 2588.0 2588.5 2526.2 2538.0 2578.3 2579.3 2582.7 2493.8 2500.0 2502.6 2517.1 2521.8 2303.2 2303.2 2313.5 2377.0 2490.6 9634.2 9811.7 9825.7 9833.7 9840.0 5567.2 5666.4 5722.7 5731.3 5733.4 3631.9 3749.8 3806.1 3825.9 3826.3 1199.1 1279.3 1282.6 1284.6 1298.5 13172.6 13511.4 13539.0 13816.5 13899.2 4869.8 4889.4 4932.0 5001.6 5049.1 10842.2 10851.8 10854.8 10873.9 10882.2 5685.7 5688.4 5689.6 5691.6 5693.6 4656.3 5135.9 5209.5 5255.2 5297.7 4046.5 4048.2 4099.3 4141.8 4180.7 1215.2 1219.8 1220.9 1240.7 1251.3 960.3 1022.9 1051.9 1059.1 1068.8 6255.2 6687.9 6699.3 6819.2 6837.2 982.2 997.5 999.0 1012.2 1029.5 4421.6 4592.9 4628.4 4744.1 4751.1 11593.2 12215.6 12687.5 12708.6 12739.6 12024.2 12406.7 12428.8 12438.9 12446.3 8970.5 9135.4 9138.7 9160.7 9185.5 6603.3 6784.7 6829.5 6896.2 6922.2 11134.3 11502.4 11523.1 11564.1 11578.7 11430.3 11467.8 11654.1 11730.6 11757.6 8781.1 8877.8 8885.4 8936.4 8990.9 9190.0 9228.6 9280.0 9305.6 9306.2 8511.3 8565.7 8630.4 8679.6 8693.6 8243.3 8334.5 8350.6 8400.1 8421.8 7491.7 7497.9 7585.8 7586.1 7586.7 5738.9 5777.2 5827.9 5836.1 6041.4 5817.4 5831.1 5891.2 5932.9 6057.4 07:19:23 INFO - PROCESS | 3443 | 07:19:23 INFO - PROCESS | 3443 | [#4] prototype.html Cycles:180 Average:4907.83 Median:5438.62 stddev:1404.22 (25.8%) stddev-sans-first:1366.08 07:19:23 INFO - PROCESS | 3443 | Values: 362.2 362.8 364.3 374.0 377.7 5568.7 5581.0 5611.6 5631.9 5644.9 5279.8 5486.2 5537.6 5553.9 5562.2 4440.5 4447.1 4453.1 4456.9 4470.4 4433.2 4438.0 4439.9 4451.5 4472.2 4169.8 4248.8 4390.3 4418.9 4424.2 4344.6 4378.6 4378.7 4382.0 4395.4 3908.4 3920.1 3922.9 3936.2 3945.5 5193.4 5290.4 5426.4 5450.8 5458.1 5013.7 5024.9 5058.6 5063.9 5069.4 5799.6 5801.2 5831.2 5866.6 5924.8 4142.5 4274.1 4300.4 4312.4 4340.1 6037.2 6075.5 6086.4 6091.8 6094.2 4349.1 4373.5 4387.6 4394.0 4399.9 4302.2 4490.1 4511.9 4521.0 4545.8 5061.4 5077.8 5090.9 5098.4 5144.2 5633.9 5894.4 5896.2 5935.8 5936.8 1550.8 1578.7 1581.2 1582.6 1586.4 1020.3 1024.1 1041.2 1046.2 1050.8 5028.6 5043.0 5076.2 5122.8 5129.9 5338.5 5344.4 5377.8 5391.5 5412.1 5134.4 5224.4 5506.3 5697.0 5704.4 3144.3 3173.2 3173.7 3178.2 3185.8 5940.5 6116.5 6118.0 6200.1 6202.8 6033.3 6037.3 6043.3 6060.6 6073.5 6119.5 6122.9 6138.3 6139.5 6144.9 5792.9 5926.9 5941.4 5973.4 5992.3 5844.9 5864.6 5877.3 5879.4 5900.9 5953.4 6100.0 6128.7 6137.6 6170.6 4384.3 4389.4 4411.6 4416.3 4418.9 5934.2 5939.6 5951.9 5989.2 5994.3 5584.8 5842.2 5848.0 5870.7 5906.1 5816.9 5830.7 5845.8 5846.1 5861.4 5470.5 5553.7 5820.4 5835.8 5839.2 5855.1 5865.0 5885.7 5888.8 5894.1 5718.3 5798.8 5804.5 5816.7 5817.6 07:19:23 INFO - PROCESS | 3443 | 07:19:23 INFO - PROCESS | 3443 | [#5] yui.html Cycles:180 Average:21544.14 Median:4217.21 stddev:30763.22 (729.5%) stddev-sans-first:30810.16 07:19:23 INFO - PROCESS | 3443 | Values: 817.7 828.3 851.1 864.9 887.9 16053.7 17184.9 17217.1 17340.6 17343.0 14130.4 14702.5 15773.3 15895.8 15922.8 738.1 875.1 891.6 892.5 893.8 858.2 878.8 887.9 891.0 892.8 946.5 947.5 947.6 948.1 948.7 755.1 783.4 937.6 937.7 938.0 579.1 650.8 677.0 680.7 681.7 16138.6 16175.2 16241.8 16249.8 16279.8 14865.4 15369.2 15898.4 15947.7 16035.0 13337.7 13452.5 13463.9 13482.2 13607.9 4536.5 4590.1 4632.2 4653.8 4755.1 85190.6 92642.0 92670.0 93063.0 93563.0 11684.3 11754.8 11790.0 12389.9 12754.0 21976.6 22139.7 22225.7 22277.1 22284.4 8674.9 8774.9 8862.9 8966.8 9204.9 4806.4 4901.4 5000.6 5128.5 5181.2 4005.3 4213.1 4221.3 4221.4 4221.5 714.2 729.1 755.1 777.9 779.1 688.7 725.1 761.3 763.2 796.5 10955.8 10992.7 11005.9 11137.4 11164.7 663.7 703.6 745.4 797.4 821.5 4981.2 5286.6 5319.5 5342.6 5369.4 115790.4 117258.0 118369.0 120983.4 123151.0 86285.6 101824.5 105934.5 106188.0 107851.5 88361.0 88750.0 93144.0 94025.0 95584.5 10660.0 10729.9 11151.9 11162.7 11183.7 47027.0 52687.5 53827.7 54026.7 54210.0 45883.6 52911.7 54075.7 54334.2 55603.4 13273.7 13652.7 13792.3 14050.6 14264.9 15457.0 15897.0 15923.4 16547.9 16649.4 12311.3 13805.8 14550.9 15110.9 15306.5 14681.5 15087.1 15129.8 15151.8 15610.1 14829.3 15014.9 15064.8 15304.9 15414.4 10123.3 10590.6 10665.6 10736.6 10892.4 12024.2 12145.8 12474.8 12581.7 12698.2 07:19:23 INFO - PROCESS | 3443 | -------- Summary: end -------- 07:19:23 INFO - PROCESS | 3443 | 07:19:24 INFO - TEST-INFO | 3443: exit 0 07:19:24 INFO - TEST-OK | dromaeo_css | took 1118064ms 07:19:24 INFO - TEST-START | kraken 07:19:24 INFO - operating with platform_type : linux_ 07:19:24 INFO - Initialising browser for kraken test... 07:19:24 INFO - TEST-INFO | started process 3656 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl8GO3V/profile http://localhost:57728/getInfo.html) 07:19:29 INFO - PROCESS | 3656 | __metrics Screen width/height:1600/1200 07:19:29 INFO - PROCESS | 3656 | colorDepth:24 07:19:29 INFO - PROCESS | 3656 | Browser inner width/height: 1024/697 07:19:29 INFO - PROCESS | 3656 | __metrics 07:19:30 INFO - PROCESS | 3656 | 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" 07:19:30 INFO - PROCESS | 3656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:31 INFO - TEST-INFO | 3656: exit 0 07:19:31 INFO - Browser initialized. 07:19:31 INFO - Running cycle 1/1 for kraken test... 07:19:31 INFO - TEST-INFO | started process 3743 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl8GO3V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:42 INFO - PROCESS | 3743 | RSS: Main: 145604608 07:19:42 INFO - PROCESS | 3743 | 07:19:45 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57728/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:46 INFO - PROCESS | 3743 | RSS: Main: 191524864 07:19:46 INFO - PROCESS | 3743 | 07:19:48 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57728/tests/kraken/driver.html?testName=audio-dft) 07:19:49 INFO - PROCESS | 3743 | RSS: Main: 181592064 07:19:49 INFO - PROCESS | 3743 | 07:19:52 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57728/tests/kraken/driver.html?testName=audio-fft) 07:19:52 INFO - PROCESS | 3743 | RSS: Main: 269262848 07:19:52 INFO - PROCESS | 3743 | 07:19:54 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57728/tests/kraken/driver.html?testName=audio-oscillator) 07:19:55 INFO - PROCESS | 3743 | RSS: Main: 175202304 07:19:55 INFO - PROCESS | 3743 | 07:19:57 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57728/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:58 INFO - PROCESS | 3743 | RSS: Main: 177315840 07:19:58 INFO - PROCESS | 3743 | 07:20:01 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57728/tests/kraken/driver.html?testName=imaging-darkroom) 07:20:01 INFO - PROCESS | 3743 | RSS: Main: 213495808 07:20:01 INFO - PROCESS | 3743 | 07:20:05 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57728/tests/kraken/driver.html?testName=imaging-desaturate) 07:20:05 INFO - PROCESS | 3743 | RSS: Main: 211296256 07:20:05 INFO - PROCESS | 3743 | 07:20:08 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57728/tests/kraken/driver.html?testName=json-parse-financial) 07:20:09 INFO - PROCESS | 3743 | RSS: Main: 210894848 07:20:09 INFO - PROCESS | 3743 | 07:20:10 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57728/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:11 INFO - PROCESS | 3743 | RSS: Main: 175730688 07:20:11 INFO - PROCESS | 3743 | 07:20:13 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57728/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:13 INFO - PROCESS | 3743 | RSS: Main: 182321152 07:20:13 INFO - PROCESS | 3743 | 07:20:15 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57728/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:16 INFO - PROCESS | 3743 | RSS: Main: 185049088 07:20:16 INFO - PROCESS | 3743 | 07:20:18 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57728/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:20:19 INFO - PROCESS | 3743 | RSS: Main: 181116928 07:20:19 INFO - PROCESS | 3743 | 07:20:22 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57728/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:20:22 INFO - PROCESS | 3743 | RSS: Main: 183885824 07:20:22 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | Cycle 1(1): loaded http://localhost:57728/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57728/tests/kraken/driver.html?testName=ai-astar) 07:20:24 INFO - PROCESS | 3743 | RSS: Main: 234950656 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | __start_tp_report 07:20:24 INFO - PROCESS | 3743 | _x_x_mozilla_page_load 07:20:24 INFO - PROCESS | 3743 | _x_x_mozilla_page_load_details 07:20:24 INFO - PROCESS | 3743 | |i|pagename|runs| 07:20:24 INFO - PROCESS | 3743 | |0;ai-astar;100;111;112;111;112;115;110;112;109;112 07:20:24 INFO - PROCESS | 3743 | |1;audio-beat-detection;147;114;116;181;120;117;144;115;151;117 07:20:24 INFO - PROCESS | 3743 | |2;audio-dft;162;159;160;160;161;162;159;160;160;160 07:20:24 INFO - PROCESS | 3743 | |3;audio-fft;75;76;75;114;74;76;74;75;98;73 07:20:24 INFO - PROCESS | 3743 | |4;audio-oscillator;84;82;137;84;84;84;128;84;83;139 07:20:24 INFO - PROCESS | 3743 | |5;imaging-gaussian-blur;117;116;117;116;118;117;117;117;116;116 07:20:24 INFO - PROCESS | 3743 | |6;imaging-darkroom;148;146;146;146;147;145;145;146;147;145 07:20:24 INFO - PROCESS | 3743 | |7;imaging-desaturate;95;96;95;96;95;96;95;95;95;95 07:20:24 INFO - PROCESS | 3743 | |8;json-parse-financial;50;50;51;50;50;52;50;50;50;50 07:20:24 INFO - PROCESS | 3743 | |9;json-stringify-tinderbox;58;58;58;58;59;58;58;59;59;59 07:20:24 INFO - PROCESS | 3743 | |10;stanford-crypto-aes;73;76;71;73;72;72;73;72;72;72 07:20:24 INFO - PROCESS | 3743 | |11;stanford-crypto-ccm;127;126;119;118;119;117;115;116;154;119 07:20:24 INFO - PROCESS | 3743 | |12;stanford-crypto-pbkdf2;166;160;161;160;157;166;163;158;158;157 07:20:24 INFO - PROCESS | 3743 | |13;stanford-crypto-sha256-iterative;65;64;62;62;68;64;60;62;60;61 07:20:24 INFO - PROCESS | 3743 | __end_tp_report 07:20:24 INFO - PROCESS | 3743 | __start_cc_report 07:20:24 INFO - PROCESS | 3743 | _x_x_mozilla_cycle_collect,1631 07:20:24 INFO - PROCESS | 3743 | __end_cc_report 07:20:24 INFO - PROCESS | 3743 | __startTimestamp1472739624503__endTimestamp 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | ------- Summary: start ------- 07:20:24 INFO - PROCESS | 3743 | Number of tests: 14 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#0] ai-astar Cycles:10 Average:110.40 Median:112.00 stddev:3.98 (3.6%) stddev-sans-first:1.67 07:20:24 INFO - PROCESS | 3743 | Values: 100.0 111.0 112.0 111.0 112.0 115.0 110.0 112.0 109.0 112.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:132.00 stddev:22.59 (17.1%) stddev-sans-first:23.32 07:20:24 INFO - PROCESS | 3743 | Values: 147.0 114.0 116.0 181.0 120.0 117.0 144.0 115.0 151.0 117.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.06 (0.7%) stddev-sans-first:0.93 07:20:24 INFO - PROCESS | 3743 | Values: 162.0 159.0 160.0 160.0 161.0 162.0 159.0 160.0 160.0 160.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:13.74 (18.3%) stddev-sans-first:14.40 07:20:24 INFO - PROCESS | 3743 | Values: 75.0 76.0 75.0 114.0 74.0 76.0 74.0 75.0 98.0 73.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#4] audio-oscillator Cycles:10 Average:98.90 Median:84.00 stddev:24.84 (29.6%) stddev-sans-first:25.76 07:20:24 INFO - PROCESS | 3743 | Values: 84.0 82.0 137.0 84.0 84.0 84.0 128.0 84.0 83.0 139.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 07:20:24 INFO - PROCESS | 3743 | Values: 117.0 116.0 117.0 116.0 118.0 117.0 117.0 117.0 116.0 116.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:0.78 07:20:24 INFO - PROCESS | 3743 | Values: 148.0 146.0 146.0 146.0 147.0 145.0 145.0 146.0 147.0 145.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 07:20:24 INFO - PROCESS | 3743 | Values: 95.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 07:20:24 INFO - PROCESS | 3743 | Values: 50.0 50.0 51.0 50.0 50.0 52.0 50.0 50.0 50.0 50.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:58.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 07:20:24 INFO - PROCESS | 3743 | Values: 58.0 58.0 58.0 58.0 59.0 58.0 58.0 59.0 59.0 59.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:72.50 stddev:1.35 (1.9%) stddev-sans-first:1.42 07:20:24 INFO - PROCESS | 3743 | Values: 73.0 76.0 71.0 73.0 72.0 72.0 73.0 72.0 72.0 72.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:119.00 stddev:11.59 (9.7%) stddev-sans-first:12.20 07:20:24 INFO - PROCESS | 3743 | Values: 127.0 126.0 119.0 118.0 119.0 117.0 115.0 116.0 154.0 119.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.60 Median:160.50 stddev:3.41 (2.1%) stddev-sans-first:3.00 07:20:24 INFO - PROCESS | 3743 | Values: 166.0 160.0 161.0 160.0 157.0 166.0 163.0 158.0 158.0 157.0 07:20:24 INFO - PROCESS | 3743 | 07:20:24 INFO - PROCESS | 3743 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:2.49 (3.9%) stddev-sans-first:2.51 07:20:24 INFO - PROCESS | 3743 | Values: 65.0 64.0 62.0 62.0 68.0 64.0 60.0 62.0 60.0 61.0 07:20:24 INFO - PROCESS | 3743 | -------- Summary: end -------- 07:20:24 INFO - PROCESS | 3743 | 07:20:26 INFO - TEST-INFO | 3743: exit 0 07:20:26 INFO - TEST-OK | kraken | took 61287ms 07:20:26 INFO - SUITE-END | took 1179s 07:20:26 INFO - Completed test suite (00:19:39) 07:20:26 INFO - javascript benchmark 07:20:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.590926134233, 3288.6053673559177, 3306.991367632872, 3312.7846689965168, 3336.5328853960436, 5663.886722265553, 5740.483356497914, 5744.569157313197, 5762.827115186551, 5786.913196302059, 5746.454086861752, 5784.1902133701315, 5802.709864506779, 5804.564657650682, 5815.127730840376, 5085.644004919657, 5139.25480805283, 5232.895342093156, 5292.629515933886, 5328.227407025979, 5167.586593072564, 5179.559737422323, 5190.221466779975, 5236.659617124894, 5247.895042099156, 5180.352455943007, 5201.2458193561915, 5263.3683957925105, 5265.052290587692, 5283.735813209343, 5327.573794096502, 5339.999999999981, 5341.919871201935, 5343.278657381243, 5348.946510534884, 5083.135866902636, 5139.563137133348, 5178.171492561172, 5241.449647786965, 5250.553702935255, 5604.607677462579, 5641.746121424505, 5661.886762264773, 5666.801661941853, 5680.971595142039, 6079.300880398722, 6088.817335479943, 6089.662210937712, 6171.907421388682, 6190.41191086846, 5906.911396329058, 6128.687451924525, 6140.048292514667, 6160.983437732769, 6196.0705894116245, 5846.7076646167525, 5851.502622277111, 5861.6189947653265, 5865.325487568899, 5870.7945221917025, 6387.840303992344, 6390.201224846895, 6391.520635952229, 6398.9040164397575, 6398.936010639927, 5855.443732845373, 5989.670568118795, 6040.275166979903, 6048.516118710492, 6116.66358350295, 6210.658413787195, 6211.782587609502, 6212.968935155295, 6221.720022598994, 6223.657698826518, 6132.41742034506, 6158.445739883432, 6165.445109940127, 6173.536984726164, 6178.474829639531, 5981.282246130492, 6049.909251361233, 6107.0228763397645, 6113.449789518964, 6171.105189747462, 4669.743164125938, 4700.905981880412, 4716.316134160596, 4718.693284936468, 4720.95279047212, 4161.0845613964875, 4179.247735407595, 4182.728122672017, 4196.454460920061, 4212.094399704079, 5663.490285874291, 5665.745041473144, 5700.971495142498, 5701.914471282934, 5788.363280039192, 6137.171481849982, 6168.19813424252, 6185.350538193425, 6190.102435146969, 6197.070439434031, 5788.797392091341, 5796.3913789052385, 5802.187693722883, 5805.332386775575, 5811.564132690065, 5210.088234558937, 5235.790568377222, 5291.153415453509, 5292.92060619091, 5365.222042803772, 6329.683515824098, 6352.174217351715, 6367.458766004941, 6369.0, 6369.394907483782, 6136.7852125175405, 6140.877182456236, 6198.845028874314, 6210.596311239755, 6211.565190436626, 6135.110408990582, 6145.893739127, 6147.047207682816, 6245.281792593728, 6308.432241098323, 6073.848153796191, 6126.24286675468, 6133.946794548733, 6138.357744607992, 6142.805741014737, 6197.225346831646, 6198.752049917953, 6200.534959877937, 6208.255009398901, 6238.095476155841, 5897.262842144732, 5996.070609055603, 6052.66710330936, 6106.175666284995, 6112.052631842072, 5903.203067585821, 6032.33644299118, 6051.909221361595, 6064.848378790565, 6069.908951365821, 5996.580239383201, 6003.249593800775, 6005.159277701038, 6005.669688167192, 6011.248593925759, 5839.970800145972, 5978.312494063064, 6051.3041000283765, 6079.160724792212, 6088.665123418255, 5894.174815525744, 5951.137085122719, 5975.133605627247, 5998.430149135826, 6002.579819412599], "value": 5669.521674772804, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.4224118123057, 599.9940000599994, 620.8354785981716, 629.0532748213939, 643.7489379142138, 35051.2989740205, 37875.242495150116, 37940.24119517612, 46968.0, 48195.0, 47894.0, 47981.0, 48463.0, 48505.27242091363, 48665.0, 9271.35100542964, 9427.151556359926, 9431.622735090623, 9435.905640943605, 9439.764005899839, 8642.092580279075, 11042.116630669567, 11323.094152467824, 11330.490127944262, 11629.24409913353, 11329.433528323592, 11417.086633069375, 11418.885811141865, 11419.828702569468, 11424.942875285611, 8584.141585841366, 11248.100151987821, 11296.887031129665, 11317.0, 11327.150463715254, 10759.83860242097, 11130.05394541465, 11148.944255278671, 11450.599229026944, 11460.083193344595, 46159.07681846378, 46526.76736616295, 46563.0, 46608.30087548689, 46619.76690116562, 3757.4551690004796, 3898.4152377143655, 4054.635082842529, 4194.622483976456, 4298.0, 35317.64682353169, 35410.822945885106, 35439.0, 35444.468332975026, 35508.822455887814, 4523.547645235451, 4537.31940208968, 4570.954290457086, 4607.585317321424, 4608.769561521911, 393105.0, 394388.0, 396340.0, 396613.0, 397121.0, 63686.36313636897, 64101.0, 64168.0, 64224.67887660532, 64312.35687643157, 39871.601283987366, 40974.1805163901, 41104.38343424851, 43319.0, 68721.65639171773, 18828.529286767665, 18899.338523151622, 18941.242350305834, 18950.24199032051, 18962.620747585246, 12307.75384492305, 14959.177245251398, 15130.773038404433, 15232.162231077176, 15241.1617361044, 19861.106250218774, 20773.169073236902, 20861.791382085983, 20862.687059694115, 21020.15919363239, 7365.5693621105975, 7607.239276072349, 7635.618219089023, 7664.4634875559295, 7858.174891636296, 1042.5412818360048, 1044.968650940473, 1054.3568423261804, 1078.687180717583, 1145.3585991844593, 34475.82762086173, 35626.643733562334, 35629.28741425157, 35644.46533302002, 35648.821755891055, 1159.8956093951585, 1171.37916904041, 1210.3222195570509, 1210.5278941212935, 1230.6615680687883, 18343.32202444125, 18385.172667230006, 18396.724049139273, 18472.630547388977, 18611.25554977813, 624824.0, 629339.0, 629862.0, 632390.0282991518, 633471.0, 66045.66977165084, 66550.0, 66570.33429665641, 66654.33345666481, 66661.0, 1300.6618279247464, 1404.1996062244627, 1427.7215942891037, 1443.0475885915246, 1446.8408475067524, 34788.478172827934, 36449.817750911076, 36489.45265821068, 36651.816740917195, 36670.8166459166, 54858.45141548533, 55561.722191388784, 55563.72218138883, 55810.0, 55840.44159558352, 420077.0, 421016.0, 421393.0, 422105.0, 422158.0, 28768.712312876603, 28966.855165724035, 29419.0, 31482.52776208313, 33919.491207631414, 52714.0, 53893.0, 54036.45963540472, 54402.727986359816, 55730.44269557253, 44377.33433998557, 45258.32112518381, 45507.04596666296, 45607.31589026233, 46282.0, 44406.0, 45510.772446137555, 45953.77023114863, 46436.0, 46895.531044689116, 44655.776721116184, 44766.55233447624, 44788.77605611951, 44904.32643510285, 44925.55074449214, 38039.80980095192, 39242.0, 39909.401358979056, 40287.79856100701, 40510.797446012584, 29850.0, 30045.0, 30229.0, 30232.244193895327, 30290.394192116473], "value": 21982.380622758465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.34570286176387, 416.8947055324212, 432.9523752387238, 437.3068686132478, 439.32344189947474, 17166.3133474661, 20521.179152833887, 21931.12275508982, 21983.340499785012, 22712.54574908501, 20714.689279660815, 20768.0, 21135.471613209676, 21348.8932555337, 21374.0, 5852.502537284326, 5936.109583562478, 6010.218671572689, 6022.397760223965, 6022.638641681491, 5882.970585147068, 5939.940600593982, 5940.257467816523, 5941.079132734433, 5944.464998150165, 5892.499137573312, 5912.3496415394275, 5924.6445213287125, 5940.990031694622, 5984.97007514964, 5840.0, 5960.7019649017375, 5991.730372133265, 5995.130706047642, 5996.22049133612, 5214.504622060898, 5335.0, 5346.652467589594, 5385.111456609655, 5386.030514507387, 25492.490150197078, 25492.74507254941, 25536.0, 25542.361440964123, 25668.614970775452, 2608.543504886656, 2647.9867600662064, 2681.7988650851266, 3182.5385319128827, 3342.9498557521656, 15610.687786244325, 15839.683206335809, 15847.366105355772, 15866.682666346609, 15893.046417214888, 2079.4593405714472, 2088.4883203615213, 2105.768365479796, 2117.95764084719, 2121.2045482943895, 87622.5618871908, 88663.0, 89181.10818891857, 90540.54729726375, 91577.0, 9929.851052234222, 10619.575216991389, 11171.944140279247, 11172.10623150146, 12248.510059597695, 16019.839801602067, 16569.25438355277, 16655.750163747554, 16755.497335079723, 16764.41324553659, 9157.130072643089, 9166.17504424605, 9175.862362064574, 9332.58003389849, 9397.76505587352, 8924.0, 8997.145271199226, 9013.0, 9208.12522810332, 9212.0, 7057.50597458173, 7099.219085900547, 7151.463640227003, 7217.386522145676, 7241.4206863451855, 2011.9698204526944, 2028.3914825552276, 2068.4001639524367, 2084.1663334666255, 2142.1752625239396, 984.675057231112, 1022.2946167144646, 1024.1141412678128, 1058.3385384134915, 1077.1221454514546, 21223.68164477534, 21756.564868702855, 22092.668609970864, 22237.332880013626, 22261.332160035225, 1080.475969154959, 1094.5129417409335, 1107.5182295701395, 1108.800415925125, 1123.8932301431514, 12331.321777302335, 12578.68553286157, 12600.369981501071, 12682.809757853454, 12748.107632465639, 573325.8199480387, 575151.0, 587081.0, 597436.0, 599696.0, 89543.0, 92301.53849230711, 92596.0, 93508.53245733728, 94185.5290723542, 1065.1106326217541, 1134.7787181499693, 1136.7669627726461, 1159.6289187459931, 1161.7211869151342, 18112.81887181164, 18560.257589696263, 18955.43133705936, 18959.052047397297, 19447.222111115396, 31845.681543185197, 41214.175716484904, 41447.9276036203, 43243.78378108089, 44487.55512444834, 61934.38065619466, 62282.0, 62338.376616233254, 62364.0, 64330.67834660984, 8535.8719619207, 8549.487030778173, 8670.26302764272, 9058.184763371328, 9073.319501037317, 12419.254844709347, 14004.36980335867, 14028.018038737599, 14114.0, 14172.29138543048, 11518.481668325114, 11838.940805295919, 12227.388630568257, 12237.082218833444, 12788.680282993, 12192.817107743569, 12361.0, 12372.443240054039, 12398.380080995734, 12545.372731363577, 12226.755464890475, 12446.190997585129, 12457.813132803196, 12582.245065295745, 12680.175788573713, 8670.0, 8816.206541411048, 8985.685501007694, 8996.325275604488, 9083.68207112748, 11563.42182890869, 11984.820227696417, 12016.879831201926, 12138.150329477205, 12434.253944763343], "value": 10090.969223207498, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1534.8618624323813, 1590.3082159260723, 1753.149722384644, 1758.3845654021086, 1887.6602211601905, 9064.864027039592, 9751.122398984082, 9756.414615123098, 9784.804303913917, 9787.070228328315, 8359.247667709906, 9061.365704400694, 9332.720018399443, 9342.0, 9520.809583808319, 2515.6478093066985, 2542.745725427461, 2544.5547029269896, 2545.6563363945816, 2555.8083143764293, 2448.8285819992607, 2582.044643481914, 2582.3673200065914, 2587.9870600647064, 2588.5470042742445, 2526.1916186820226, 2537.961930571043, 2578.252306831026, 2579.3035880312304, 2582.7288134745863, 2493.7506249375097, 2499.950000999988, 2502.5620516409554, 2517.1190083470824, 2521.7730404263702, 2303.159346838392, 2303.1823702585507, 2313.5257272259146, 2376.9881150594138, 2490.57660197765, 9634.229261659051, 9811.656592019317, 9825.705228843075, 9833.655822046194, 9840.0, 5567.164925261202, 5666.376698563155, 5722.656640601577, 5731.254936858223, 5733.426657334275, 3631.909202269938, 3749.793761343125, 3806.105565192205, 3825.885223443301, 3826.330392181357, 1199.1426130316866, 1279.2772083772638, 1282.647272000198, 1284.601773450224, 1298.4611386274685, 13172.60482185536, 13511.391987360494, 13539.0, 13816.516421925184, 13899.235542045184, 4869.756512174377, 4889.3888263967, 4931.950680493149, 5001.649884508122, 5049.091163590516, 10842.241043126905, 10851.837222441669, 10854.837177442345, 10873.945630271797, 10882.238243322892, 5685.744141513642, 5688.374278829326, 5689.630174038674, 5691.630044047207, 5693.629914055573, 4656.348111264426, 5135.87160320995, 5209.453007434241, 5255.237990491358, 5297.735113244323, 4046.5346485153996, 4048.2308361411237, 4099.344104943195, 4141.81361838718, 4180.728252663628, 1215.155466950461, 1219.8353222314875, 1220.8718084601167, 1240.7456471423336, 1251.305525433375, 960.279790157382, 1022.928395012342, 1051.8842927277992, 1059.0838924330399, 1068.780899915531, 6255.186825712628, 6687.933120668828, 6699.330066993261, 6819.24988251292, 6837.213720422215, 982.2387649571497, 997.5411310796969, 998.960041598328, 1012.175077312003, 1029.5264178477985, 4421.624161946206, 4592.931106033412, 4628.4445866496235, 4744.074905393415, 4751.073540659539, 11593.24643898144, 12215.572454964034, 12687.492500299884, 12708.555200567936, 12739.61781146567, 12024.158308918291, 12406.689832754073, 12428.813567796491, 12438.937805310916, 12446.25322480654, 8970.506622135714, 9135.360524763202, 9138.68014619485, 9160.6793762218, 9185.540722963826, 6603.339666033358, 6784.660766961731, 6829.4877884157895, 6896.206936202401, 6922.203946546211, 11134.33194008362, 11502.367369794742, 11523.13576481784, 11564.074874010228, 11578.71053223676, 11430.314181149157, 11467.770644587228, 11654.067674585844, 11730.589429370273, 11757.588484403004, 8781.077986811548, 8877.822443551222, 8885.378023538291, 8936.374453788432, 8990.86513702308, 9189.954050229973, 9228.584713688035, 9280.025597312186, 9305.581248843682, 9306.162445379949, 8511.276541494042, 8565.700200492953, 8630.352723545633, 8679.566021698764, 8693.608787604446, 8243.25810677042, 8334.541600211804, 8350.62422191015, 8400.117987611213, 8421.831563368822, 7491.70033198666, 7497.887531687138, 7585.848283034419, 7586.089669239726, 7586.734464293723, 5738.856528586819, 5777.24895763548, 5827.9417205829095, 5836.066229403275, 6041.426064523863, 5817.360090390139, 5831.1253312001845, 5891.204687367151, 5932.911006334822, 6057.42454466825], "value": 5082.618308113436, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.17243598377706, 362.7859562857913, 364.2623687033756, 373.9644733750293, 377.6525596451265, 5568.6658800472, 5581.0, 5611.551075913932, 5631.943680563193, 5644.915326270109, 5279.788808447657, 5486.204500347447, 5537.58468114891, 5553.888922221553, 5562.193481945115, 4440.467143942716, 4447.0661161156195, 4453.131639330332, 4456.910861782762, 4470.441194850644, 4433.157700036999, 4437.977810110945, 4439.933400998972, 4451.465824101112, 4472.21736196166, 4169.76991787422, 4248.83004679814, 4390.341448782676, 4418.889527761799, 4424.181526417607, 4344.567042456219, 4378.562143785628, 4378.671599630009, 4381.9561804381865, 4395.406620106292, 3908.4137379393032, 3920.0595705126143, 3922.921541569181, 3936.153726948691, 3945.5462621798574, 5193.376794784613, 5290.3599884036075, 5426.430224826376, 5450.811275403894, 5458.099413596752, 5013.749312534395, 5024.899502009976, 5058.570021548136, 5063.949360506384, 5069.44236134025, 5799.550112471882, 5801.24583804107, 5831.154482600042, 5866.6480011199465, 5924.763009479616, 4142.502899652061, 4274.102438487921, 4300.35494675798, 4312.374705667692, 4340.066885619575, 6037.154798328237, 6075.513958883279, 6086.421789929949, 6091.847703807396, 6094.177286066369, 4349.13017396522, 4373.453318335208, 4387.583179597933, 4394.0, 4399.934000989988, 4302.204092242882, 4490.146872094291, 4511.932321015187, 4520.954790452053, 4545.772711364419, 5061.4179369373, 5077.771500282496, 5090.872728181825, 5098.413682426494, 5144.202648589399, 5633.8591535211945, 5894.38108998549, 5896.174535565025, 5935.762569497172, 5936.821895343147, 1550.8139023317274, 1578.6684796192928, 1581.2172974377756, 1582.6122599962853, 1586.4050980882168, 1020.336781092296, 1024.134606257711, 1041.193075366597, 1046.1892033673812, 1050.826613608746, 5028.572571331478, 5042.974785126051, 5076.162433198555, 5122.769475373542, 5129.922381435525, 5338.546223570962, 5344.412114667383, 5377.838664840061, 5391.487808658171, 5412.134058550613, 5134.358205224347, 5224.399194092652, 5506.339239291311, 5696.971515142398, 5704.372519022904, 3144.2768163322107, 3173.222560472699, 3173.6826317368077, 3178.1577881861326, 3185.7699610272903, 5940.495057920125, 6116.510679145657, 6118.0, 6200.0699895015205, 6202.82146392184, 6033.306169790442, 6037.335893051761, 6043.335233124353, 6060.575759696779, 6073.48375388097, 6119.541034422347, 6122.908156377658, 6138.294096178818, 6139.508839292846, 6144.8771024579255, 5792.94207057924, 5926.881462370815, 5941.435563621449, 5973.432523910222, 5992.250968628921, 5844.94155058461, 5864.618799778001, 5877.326670830731, 5879.412058794086, 5900.940990590039, 5953.434423729739, 6100.0, 6128.693565321627, 6137.570370074052, 6170.598911070766, 4384.254676704904, 4389.410247953722, 4411.580899814512, 4416.271315232951, 4418.933715994199, 5934.1988831508925, 5939.643621382731, 5951.851203719942, 5989.191459153133, 5994.28068631767, 5584.776608935597, 5842.1820945068575, 5847.970760146342, 5870.677112758668, 5906.114082887601, 5816.941830581809, 5830.679312637845, 5845.766169353178, 5846.093855452412, 5861.3845546217035, 5470.480304371078, 5553.722313884209, 5820.447057529528, 5835.824925252249, 5839.182514448065, 5855.092460668603, 5865.0, 5885.735141918538, 5888.764449422147, 5894.145348924468, 5718.340971448297, 5798.797042103675, 5804.477597016289, 5816.73824677882, 5817.621854579439], "value": 4470.299280041104, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.705625974649, 828.2545708862024, 851.0595791650227, 864.8875646165998, 887.9289656827455, 16053.678926421479, 17184.91407542962, 17217.13914304283, 17340.56648583786, 17343.0, 14130.434782608683, 14702.485413010547, 15773.290201940912, 15895.761563576556, 15922.76115858263, 738.1400668221515, 875.1248751248767, 891.6121487153076, 892.5448021509045, 893.8167675626481, 858.1975852577859, 878.7583414561014, 887.8579427291602, 890.9599068041921, 892.7812685891965, 946.4699768129835, 947.4741518457254, 947.6114792934898, 948.1182500274741, 948.7106432538073, 755.0599503618031, 783.4046124945082, 937.6202637931615, 937.6624415210518, 937.9812403751921, 579.0879365000142, 650.8275307043638, 677.014943257564, 680.7413182990432, 681.6659836680041, 16138.596535086599, 16175.19124043793, 16241.83758162427, 16249.756253656205, 16279.83720162795, 14865.405383784744, 15369.154696491689, 15898.443554475538, 15947.681046379124, 16035.037897726173, 13337.666558336023, 13452.5291614793, 13463.932680336633, 13482.191068535822, 13607.863921360859, 4536.500984891659, 4590.127875703639, 4632.2125238709505, 4653.813847446098, 4755.096531659008, 85190.57404712937, 92642.0, 92670.0, 93063.0, 93562.99999999932, 11684.298942063504, 11754.823677644843, 11790.0, 12389.876101239053, 12754.04344674153, 21976.560468790536, 22139.667904981437, 22225.666615000788, 22277.108915643516, 22284.44288892758, 8674.86987695185, 8774.912250877538, 8862.911370886337, 8966.775830604156, 9204.907950920538, 4806.423229212517, 4901.362822833009, 5000.574951129122, 5128.512791284822, 5181.222816577543, 4005.2590270799997, 4213.073123912734, 4221.345691417835, 4221.430106935586, 4221.472315960505, 714.1537278325201, 729.0631538473143, 755.0976582983392, 777.941654375924, 779.0806847919396, 688.6935313785417, 725.1262229014026, 761.2615762710162, 763.1910175214291, 796.5419883566972, 10955.83566246507, 10992.670219893416, 11005.889941100646, 11137.387443690675, 11164.665060048212, 663.7477758451854, 703.6270776488469, 745.3776096958992, 797.4338219863962, 821.4947807098591, 4981.2030075187795, 5286.577073834084, 5319.46805319465, 5342.5993050521365, 5369.40936496985, 115790.42104789423, 117258.0, 118369.0, 120983.39508302402, 123151.0, 86285.568572158, 101824.49087754513, 105934.47032764787, 106188.0, 107851.46074269735, 88361.0, 88750.0, 93144.0, 94025.0, 95584.52207738916, 10660.0, 10729.9463502682, 11151.944240278746, 11162.720931976766, 11183.72040698989, 47027.0, 52687.473125268254, 53827.730861345444, 54026.72986635042, 54210.0, 45883.611424915754, 52911.73544132255, 54075.72962135164, 54334.184987224435, 55603.443965559825, 13273.668158295734, 13652.65868353299, 13792.310384480936, 14050.578482645536, 14264.857351426353, 15457.0, 15897.0, 15923.363065477251, 16547.917260413622, 16649.41727039548, 12311.322877241837, 13805.79291310611, 14550.854491455373, 15110.924445377703, 15306.464273750365, 14681.48614798477, 15087.133444645482, 15129.773053403987, 15151.848481515484, 15610.141442220787, 14829.332680029209, 15014.924925375302, 15064.774028389802, 15304.923475382997, 15414.383424662889, 10123.291369604058, 10590.629327973484, 10665.573377064831, 10736.624218152327, 10892.353111317185, 12024.158308918291, 12145.817812732992, 12474.75050499003, 12581.685457863627, 12698.174618649758], "value": 8100.913180722509, "unit": "score"}], "name": "dromaeo_css", "value": 7835.965686346825, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 112.0, 111.0, 112.0, 115.0, 110.0, 112.0, 109.0, 112.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 116.0, 181.0, 120.0, 117.0, 144.0, 115.0, 151.0, 117.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 160.0, 160.0, 161.0, 162.0, 159.0, 160.0, 160.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 114.0, 74.0, 76.0, 74.0, 75.0, 98.0, 73.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 137.0, 84.0, 84.0, 84.0, 128.0, 84.0, 83.0, 139.0], "value": 98.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 118.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0, 146.0, 147.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 50.0, 52.0, 50.0, 50.0, 50.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 59.0, 58.0, 58.0, 59.0, 59.0, 59.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 71.0, 73.0, 72.0, 72.0, 73.0, 72.0, 72.0, 72.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 126.0, 119.0, 118.0, 119.0, 117.0, 115.0, 116.0, 154.0, 119.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 160.0, 161.0, 160.0, 157.0, 166.0, 163.0, 158.0, 158.0, 157.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 62.0, 62.0, 68.0, 64.0, 60.0, 62.0, 60.0, 61.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1468.5999999999997, "alertThreshold": 2.0}]} 07:20:26 INFO - Return code: 0 07:20:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:20:26 INFO - # TBPL SUCCESS # 07:20:26 INFO - Running post-action listener: _resource_record_post_action 07:20:26 INFO - [mozharness: 2016-09-01 14:20:26.314233Z] Finished run-tests step (success) 07:20:26 INFO - Running post-run listener: _resource_record_post_run 07:20:26 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 7778304; Write bytes: 493879296; Read time: 1440; Write time: 657544 07:20:26 INFO - TinderboxPrint: CPU usage
13.0% 07:20:26 INFO - TinderboxPrint: I/O read bytes / time
7,778,304 / 1,440 07:20:26 INFO - TinderboxPrint: I/O write bytes / time
493,879,296 / 657,544 07:20:26 INFO - TinderboxPrint: CPU idle
8,305.9 (87.0%) 07:20:26 INFO - TinderboxPrint: CPU user
1,185.7 (12.4%) 07:20:26 INFO - TinderboxPrint: Swap in / out
0 / 0 07:20:26 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 79306752; Read time: 16; Write time: 174224 07:20:26 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 5537792; Write bytes: 414572544; Read time: 1188; Write time: 483320 07:20:26 INFO - Running post-run listener: _upload_blobber_files 07:20:26 INFO - Blob upload gear active. 07:20:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:20:26 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 07:20:26 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'] 07:20:26 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 07:20:27 INFO - (blobuploader) - INFO - Open directory for files ... 07:20:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:20:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:34 INFO - (blobuploader) - INFO - Done attempting. 07:20:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:20:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:20:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:35 INFO - (blobuploader) - INFO - Done attempting. 07:20:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:20:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:20:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:36 INFO - (blobuploader) - INFO - Done attempting. 07:20:36 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:36 INFO - Return code: 0 07:20:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/736d22419e448eb882485ac271b928d6b8eb70fe9244ca769619ce5eb79e9ad1af39a268f7b1f4c9da7795cfdf06b19d3da7660f8d5d1af0f3db10649cc988dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4cca53320c7ee5eb1efb77be24e58d5407b5509dd7e0d91ea9fac9915e3b42e2e091b5ab473e48c9d9e9d0e336eae86161ca03ecbcf8ee6396f95421fa03ee91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b06a09f29295649625de841f9c3e5bf6a749daa1f59cc64fd7eda675233ac9a25c8776fbb06d752a80df4ddb184a7afa58865720131587f36ba8fdc5435dc23"} 07:20:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:36 INFO - Contents: 07:20:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/736d22419e448eb882485ac271b928d6b8eb70fe9244ca769619ce5eb79e9ad1af39a268f7b1f4c9da7795cfdf06b19d3da7660f8d5d1af0f3db10649cc988dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4cca53320c7ee5eb1efb77be24e58d5407b5509dd7e0d91ea9fac9915e3b42e2e091b5ab473e48c9d9e9d0e336eae86161ca03ecbcf8ee6396f95421fa03ee91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b06a09f29295649625de841f9c3e5bf6a749daa1f59cc64fd7eda675233ac9a25c8776fbb06d752a80df4ddb184a7afa58865720131587f36ba8fdc5435dc23"} 07:20:36 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:36 INFO - Copying logs to upload dir... 07:20:36 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1689.295405 ========= master_lag: 15.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 24 secs) (at 2016-09-01 07:20:51.668222) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:51.680152) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688 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/736d22419e448eb882485ac271b928d6b8eb70fe9244ca769619ce5eb79e9ad1af39a268f7b1f4c9da7795cfdf06b19d3da7660f8d5d1af0f3db10649cc988dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4cca53320c7ee5eb1efb77be24e58d5407b5509dd7e0d91ea9fac9915e3b42e2e091b5ab473e48c9d9e9d0e336eae86161ca03ecbcf8ee6396f95421fa03ee91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b06a09f29295649625de841f9c3e5bf6a749daa1f59cc64fd7eda675233ac9a25c8776fbb06d752a80df4ddb184a7afa58865720131587f36ba8fdc5435dc23"} build_url:https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011192 build_url: 'https://queue.taskcluster.net/v1/task/f5E-LrHRSJO8XO67pPY81g/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/736d22419e448eb882485ac271b928d6b8eb70fe9244ca769619ce5eb79e9ad1af39a268f7b1f4c9da7795cfdf06b19d3da7660f8d5d1af0f3db10649cc988dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4cca53320c7ee5eb1efb77be24e58d5407b5509dd7e0d91ea9fac9915e3b42e2e091b5ab473e48c9d9e9d0e336eae86161ca03ecbcf8ee6396f95421fa03ee91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b06a09f29295649625de841f9c3e5bf6a749daa1f59cc64fd7eda675233ac9a25c8776fbb06d752a80df4ddb184a7afa58865720131587f36ba8fdc5435dc23"}' ========= master_lag: 2.50 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:54.188096) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2016-09-01 07:20:54.188504) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1472734209.185048-1175323688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 9.81 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2016-09-01 07:21:04.007050) ========= ========= Started reboot skipped (results: 3, elapsed: 5 secs) (at 2016-09-01 07:21:04.007430) ========= ========= Finished reboot skipped (results: 3, elapsed: 5 secs) (at 2016-09-01 07:21:09.270020) ========= ========= Total master_lag: 27.70 =========