builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-065 starttime: 1472767131.31 results: success (0) buildid: 20160901120620 builduid: e166f3f50ebe4b44a0b35cbbecf2aa5c revision: 97d651e84739f5fadf1c39604ce3235122549176 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:51.311352) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:51.313284) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:51.313615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:51.333371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:51.333672) ========= 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=523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 14:58:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.4M=0s 2016-09-01 14:58:52 (42.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080799 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:51.428814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:51.429163) ========= 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=523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014431 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:51.478817) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:51.479207) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 97d651e84739f5fadf1c39604ce3235122549176 --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 97d651e84739f5fadf1c39604ce3235122549176 --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=523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 14:58:52,558 truncating revision to first 12 chars 2016-09-01 14:58:52,558 Setting DEBUG logging. 2016-09-01 14:58:52,558 attempt 1/10 2016-09-01 14:58:52,558 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/97d651e84739?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 14:58:52,880 unpacking tar archive at: mozilla-aurora-97d651e84739/testing/mozharness/ program finished with exit code 0 elapsedTime=0.753981 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:52.250823) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:52.251115) ========= script_repo_revision: 97d651e84739f5fadf1c39604ce3235122549176 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:52.251475) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:52.251743) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 14:58:52.264327) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-01 14:58:52.264589) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:58:53 INFO - MultiFileLogger online at 20160901 14:58:53 in /builds/slave/test 14:58:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:58:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:58:53 INFO - {'append_to_log': False, 14:58:53 INFO - 'base_work_dir': '/builds/slave/test', 14:58:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:58:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:58:53 INFO - 'branch': 'Mozilla-Aurora', 14:58:53 INFO - 'buildbot_json_path': 'buildprops.json', 14:58:53 INFO - 'config_files': ('talos/linux_config.py',), 14:58:53 INFO - 'default_actions': ('clobber', 14:58:53 INFO - 'read-buildbot-config', 14:58:53 INFO - 'download-and-extract', 14:58:53 INFO - 'populate-webroot', 14:58:53 INFO - 'create-virtualenv', 14:58:53 INFO - 'install', 14:58:53 INFO - 'run-tests'), 14:58:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:58:53 INFO - 'download_minidump_stackwalk': True, 14:58:53 INFO - 'download_symbols': 'ondemand', 14:58:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:58:53 INFO - 'tooltool.py': '/tools/tooltool.py', 14:58:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:58:53 INFO - '/tools/misc-python/virtualenv.py')}, 14:58:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:58:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:58:53 INFO - 'installer_path': 'installer.exe', 14:58:53 INFO - 'log_level': 'info', 14:58:53 INFO - 'log_name': 'talos', 14:58:53 INFO - 'log_to_console': True, 14:58:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:58:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:58:53 INFO - 'opt_config_files': (), 14:58:53 INFO - 'pip_index': False, 14:58:53 INFO - 'sps_profile': False, 14:58:53 INFO - 'sps_profile_interval': 0, 14:58:53 INFO - 'suite': 'dromaeojs-e10s', 14:58:53 INFO - 'system_bits': '32', 14:58:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:58:53 INFO - 'title': 'talos-linux64-ix-065', 14:58:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:58:53 INFO - 'use_talos_json': True, 14:58:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:58:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:58:53 INFO - 'work_dir': 'build'} 14:58:53 INFO - [mozharness: 2016-09-01 21:58:53.437380Z] Running clobber step. 14:58:53 INFO - Running pre-action listener: _resource_record_pre_action 14:58:53 INFO - Running main action method: clobber 14:58:53 INFO - rmtree: /builds/slave/test/build 14:58:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:58:53 INFO - Running post-action listener: _resource_record_post_action 14:58:53 INFO - [mozharness: 2016-09-01 21:58:53.904939Z] Finished clobber step (success) 14:58:53 INFO - [mozharness: 2016-09-01 21:58:53.905039Z] Running read-buildbot-config step. 14:58:53 INFO - Running pre-action listener: _resource_record_pre_action 14:58:53 INFO - Running main action method: read_buildbot_config 14:58:53 INFO - Using buildbot properties: 14:58:53 INFO - { 14:58:53 INFO - "project": "", 14:58:53 INFO - "product": "firefox", 14:58:53 INFO - "script_repo_revision": "production", 14:58:53 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 14:58:53 INFO - "repository": "", 14:58:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 14:58:53 INFO - "buildid": "20160901120620", 14:58:53 INFO - "pgo_build": "True", 14:58:53 INFO - "basedir": "/builds/slave/test", 14:58:53 INFO - "buildnumber": 533, 14:58:53 INFO - "slavename": "talos-linux64-ix-065", 14:58:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:58:53 INFO - "platform": "ubuntu64_hw", 14:58:53 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:58:53 INFO - "branch": "mozilla-aurora", 14:58:53 INFO - "repo_path": "releases/mozilla-aurora", 14:58:53 INFO - "revision": "97d651e84739f5fadf1c39604ce3235122549176", 14:58:53 INFO - "stage_platform": "linux64-pgo", 14:58:53 INFO - "builduid": "e166f3f50ebe4b44a0b35cbbecf2aa5c", 14:58:53 INFO - "slavebuilddir": "test" 14:58:53 INFO - } 14:58:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 14:58:53 INFO - Running post-action listener: _resource_record_post_action 14:58:53 INFO - [mozharness: 2016-09-01 21:58:53.907467Z] Finished read-buildbot-config step (success) 14:58:53 INFO - [mozharness: 2016-09-01 21:58:53.907555Z] Running download-and-extract step. 14:58:53 INFO - Running pre-action listener: _resource_record_pre_action 14:58:53 INFO - Running main action method: download_and_extract 14:58:53 INFO - mkdir: /builds/slave/test/build/tests 14:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:53 INFO - https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:58:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:58:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:58:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/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 14:58:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/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 14:58:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:58:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:58:53 INFO - retry: Failed, sleeping 30 seconds before retrying 14:59:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/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 14:59:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:59:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:59:23 INFO - retry: Failed, sleeping 60 seconds before retrying 15:00:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 15:00:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:00:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:00:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 15:00:24 INFO - Caught exception: HTTP Error 404: Not Found 15:00:24 INFO - Caught exception: HTTP Error 404: Not Found 15:00:24 INFO - Caught exception: HTTP Error 404: Not Found 15:00:24 INFO - trying https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:00:24 INFO - Downloading https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:00:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:00:24 INFO - Downloaded 1361 bytes. 15:00:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:00:24 INFO - Using the following test package requirements: 15:00:24 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 15:00:24 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:00:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:00:24 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:00:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:00:24 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:00:24 INFO - u'jsshell-linux-x86_64.zip'], 15:00:24 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:00:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:00:24 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 15:00:24 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:00:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:00:24 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:00:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:00:24 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:00:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:00:24 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:00:24 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:00:24 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:00:24 INFO - https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:00:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:00:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:00:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:00:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:00:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:00:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:00:24 INFO - retry: Failed, sleeping 30 seconds before retrying 15:00:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:00:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:00:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:00:54 INFO - retry: Failed, sleeping 60 seconds before retrying 15:01:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:01:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:01:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:01:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 15:01:54 INFO - Caught exception: HTTP Error 404: Not Found 15:01:54 INFO - Caught exception: HTTP Error 404: Not Found 15:01:54 INFO - Caught exception: HTTP Error 404: Not Found 15:01:54 INFO - trying https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:01:54 INFO - Downloading https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:01:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:01:55 INFO - Downloaded 21770970 bytes. 15:01:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:01:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:01:57 INFO - Return code: 0 15:01:57 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:57 INFO - https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:01:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:01:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:01:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:01:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:01:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:01:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:01:57 INFO - retry: Failed, sleeping 30 seconds before retrying 15:02:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:02:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:02:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:02:27 INFO - retry: Failed, sleeping 60 seconds before retrying 15:03:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:03:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:03:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:03:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 15:03:27 INFO - Caught exception: HTTP Error 404: Not Found 15:03:27 INFO - Caught exception: HTTP Error 404: Not Found 15:03:27 INFO - Caught exception: HTTP Error 404: Not Found 15:03:27 INFO - trying https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:03:27 INFO - Downloading https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:03:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:03:28 INFO - Downloaded 21770970 bytes. 15:03:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:03:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:03:29 INFO - Return code: 0 15:03: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')]} 15:03:29 INFO - https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:03:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:03:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:03:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:03:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:03:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:03:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:03:29 INFO - retry: Failed, sleeping 30 seconds before retrying 15:03:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 15:03:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:03:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:03:59 INFO - retry: Failed, sleeping 60 seconds before retrying 15:04:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 15:04:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:04:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:04:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 15:04:59 INFO - Caught exception: HTTP Error 404: Not Found 15:04:59 INFO - Caught exception: HTTP Error 404: Not Found 15:04:59 INFO - Caught exception: HTTP Error 404: Not Found 15:04:59 INFO - trying https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:04:59 INFO - Downloading https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:04:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:05:00 INFO - Downloaded 14413740 bytes. 15:05:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:05:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:05:01 INFO - Return code: 0 15:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:01 INFO - https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:05:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:05:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:05:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:05:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:05:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:05:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:05:01 INFO - retry: Failed, sleeping 30 seconds before retrying 15:05:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 15:05:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:05:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:05:31 INFO - retry: Failed, sleeping 60 seconds before retrying 15:06:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 15:06:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:06:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:06:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 15:06:31 INFO - Caught exception: HTTP Error 404: Not Found 15:06:31 INFO - Caught exception: HTTP Error 404: Not Found 15:06:31 INFO - Caught exception: HTTP Error 404: Not Found 15:06:31 INFO - trying https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:06:31 INFO - Downloading https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:06:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:06:32 INFO - Downloaded 63898337 bytes. 15:06:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:06:32 INFO - mkdir: /builds/slave/test/properties 15:06:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:06:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:06:32 INFO - Contents: 15:06:32 INFO - build_url:https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:06:32 INFO - Running post-action listener: _resource_record_post_action 15:06:32 INFO - Running post-action listener: set_extra_try_arguments 15:06:32 INFO - [mozharness: 2016-09-01 22:06:32.969277Z] Finished download-and-extract step (success) 15:06:32 INFO - [mozharness: 2016-09-01 22:06:32.969511Z] Running populate-webroot step. 15:06:32 INFO - Running pre-action listener: _resource_record_pre_action 15:06:32 INFO - Running main action method: populate_webroot 15:06:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:06:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:06:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:06:32 INFO - '--tppagecycles', 15:06:32 INFO - '7'], 15:06:32 INFO - 'tests': ['tsvgm']}}, 15:06:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:06:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:06:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:06:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:06:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:06:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:06:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['damp', 'tps']}, 15:06:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'tests': ['damp', 'tps']}, 15:06:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['dromaeo_dom']}, 15:06:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:06:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['basic_compositor_video']}, 15:06:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:06:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['a11yr', 15:06:32 INFO - 'ts_paint', 15:06:32 INFO - 'tpaint', 15:06:32 INFO - 'sessionrestore', 15:06:32 INFO - 'sessionrestore_no_auto_restore', 15:06:32 INFO - 'tabpaint']}, 15:06:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['tsvgx', 15:06:32 INFO - 'tsvgr_opacity', 15:06:32 INFO - 'tart', 15:06:32 INFO - 'tscrollx', 15:06:32 INFO - 'cart']}, 15:06:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:06:32 INFO - 'tsvgr_opacity', 15:06:32 INFO - 'tart', 15:06:32 INFO - 'tscrollx', 15:06:32 INFO - 'cart']}, 15:06:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--disable-e10s'], 15:06:32 INFO - 'tests': ['tp5o']}, 15:06:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'tests': ['tp5o']}, 15:06:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--disable-e10s', 15:06:32 INFO - '--xperf_path', 15:06:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:06:32 INFO - 'tests': ['tp5n']}, 15:06:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:06:32 INFO - 'talos_options': ['--xperf_path', 15:06:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:06:32 INFO - 'tests': ['tp5n']}}, 15:06:32 INFO - 'talos.zip': {'path': '', 15:06:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:06:32 INFO - Running post-action listener: _resource_record_post_action 15:06:32 INFO - [mozharness: 2016-09-01 22:06:32.984886Z] Finished populate-webroot step (success) 15:06:32 INFO - [mozharness: 2016-09-01 22:06:32.984975Z] Running create-virtualenv step. 15:06:32 INFO - Running pre-action listener: _resource_record_pre_action 15:06:32 INFO - Running main action method: create_virtualenv 15:06:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:06:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:06:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:06:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:06:33 INFO - Using real prefix '/usr' 15:06:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:06:33 INFO - Installing distribute.............................................................................................................................................................................................done. 15:06:35 INFO - Installing pip.................done. 15:06:35 INFO - Return code: 0 15:06:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:35 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 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:06:35 INFO - Using env: {'DISPLAY': ':0', 15:06:35 INFO - 'HOME': '/home/cltbld', 15:06:35 INFO - 'LANG': 'en_US.UTF-8', 15:06:35 INFO - 'LANGUAGE': 'en_US:en', 15:06:35 INFO - 'LOGNAME': 'cltbld', 15:06:35 INFO - 'MAIL': '/var/mail/cltbld', 15:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:35 INFO - 'MOZ_NO_REMOTE': '1', 15:06:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:35 INFO - 'NO_EM_RESTART': '1', 15:06:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:35 INFO - 'PWD': '/builds/slave/test', 15:06:35 INFO - 'SHELL': '/bin/bash', 15:06:35 INFO - 'SHLVL': '1', 15:06:35 INFO - 'TERM': 'linux', 15:06:35 INFO - 'TMOUT': '86400', 15:06:35 INFO - 'USER': 'cltbld', 15:06:35 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:35 INFO - '_': '/tools/buildbot/bin/python'} 15:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:06:35 INFO - Cleaning up... 15:06:35 INFO - Return code: 0 15:06:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:35 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 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:06:35 INFO - Using env: {'DISPLAY': ':0', 15:06:35 INFO - 'HOME': '/home/cltbld', 15:06:35 INFO - 'LANG': 'en_US.UTF-8', 15:06:35 INFO - 'LANGUAGE': 'en_US:en', 15:06:35 INFO - 'LOGNAME': 'cltbld', 15:06:35 INFO - 'MAIL': '/var/mail/cltbld', 15:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:35 INFO - 'MOZ_NO_REMOTE': '1', 15:06:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:35 INFO - 'NO_EM_RESTART': '1', 15:06:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:35 INFO - 'PWD': '/builds/slave/test', 15:06:35 INFO - 'SHELL': '/bin/bash', 15:06:35 INFO - 'SHLVL': '1', 15:06:35 INFO - 'TERM': 'linux', 15:06:35 INFO - 'TMOUT': '86400', 15:06:35 INFO - 'USER': 'cltbld', 15:06:35 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:35 INFO - '_': '/tools/buildbot/bin/python'} 15:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:35 INFO - Downloading/unpacking psutil>=3.1.1 15:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:06:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:06:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:06:37 INFO - Installing collected packages: psutil 15:06:37 INFO - Running setup.py install for psutil 15:06:38 INFO - building 'psutil._psutil_linux' extension 15:06:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:06:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:06:38 INFO - building 'psutil._psutil_posix' extension 15:06:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:06:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:06:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:06:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:06:38 INFO - Successfully installed psutil 15:06:38 INFO - Cleaning up... 15:06:38 INFO - Return code: 0 15:06:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:06:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:38 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 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:06:38 INFO - Using env: {'DISPLAY': ':0', 15:06:38 INFO - 'HOME': '/home/cltbld', 15:06:38 INFO - 'LANG': 'en_US.UTF-8', 15:06:38 INFO - 'LANGUAGE': 'en_US:en', 15:06:38 INFO - 'LOGNAME': 'cltbld', 15:06:38 INFO - 'MAIL': '/var/mail/cltbld', 15:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:38 INFO - 'MOZ_NO_REMOTE': '1', 15:06:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:38 INFO - 'NO_EM_RESTART': '1', 15:06:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:38 INFO - 'PWD': '/builds/slave/test', 15:06:38 INFO - 'SHELL': '/bin/bash', 15:06:38 INFO - 'SHLVL': '1', 15:06:38 INFO - 'TERM': 'linux', 15:06:38 INFO - 'TMOUT': '86400', 15:06:38 INFO - 'USER': 'cltbld', 15:06:38 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:38 INFO - '_': '/tools/buildbot/bin/python'} 15:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:06:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:06:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:06:40 INFO - Installing collected packages: mozsystemmonitor 15:06:40 INFO - Running setup.py install for mozsystemmonitor 15:06:40 INFO - Successfully installed mozsystemmonitor 15:06:40 INFO - Cleaning up... 15:06:40 INFO - Return code: 0 15:06:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:40 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 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:06:40 INFO - Using env: {'DISPLAY': ':0', 15:06:40 INFO - 'HOME': '/home/cltbld', 15:06:40 INFO - 'LANG': 'en_US.UTF-8', 15:06:40 INFO - 'LANGUAGE': 'en_US:en', 15:06:40 INFO - 'LOGNAME': 'cltbld', 15:06:40 INFO - 'MAIL': '/var/mail/cltbld', 15:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:40 INFO - 'MOZ_NO_REMOTE': '1', 15:06:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:40 INFO - 'NO_EM_RESTART': '1', 15:06:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:40 INFO - 'PWD': '/builds/slave/test', 15:06:40 INFO - 'SHELL': '/bin/bash', 15:06:40 INFO - 'SHLVL': '1', 15:06:40 INFO - 'TERM': 'linux', 15:06:40 INFO - 'TMOUT': '86400', 15:06:40 INFO - 'USER': 'cltbld', 15:06:40 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:40 INFO - '_': '/tools/buildbot/bin/python'} 15:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:41 INFO - Downloading/unpacking jsonschema==2.5.1 15:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:06:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:43 INFO - Downloading functools32-3.2.3-2.tar.gz 15:06:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:06:43 INFO - warning: no files found matching '*.txt' 15:06:43 INFO - no previously-included directories found matching 'build' 15:06:43 INFO - no previously-included directories found matching 'dist' 15:06:43 INFO - no previously-included directories found matching '.git*' 15:06:43 INFO - Installing collected packages: jsonschema, functools32 15:06:43 INFO - Running setup.py install for functools32 15:06:43 INFO - warning: no files found matching '*.txt' 15:06:43 INFO - no previously-included directories found matching 'build' 15:06:43 INFO - no previously-included directories found matching 'dist' 15:06:43 INFO - no previously-included directories found matching '.git*' 15:06:43 INFO - Successfully installed jsonschema functools32 15:06:43 INFO - Cleaning up... 15:06:43 INFO - Return code: 0 15:06:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:43 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 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:06:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:06:43 INFO - Using env: {'DISPLAY': ':0', 15:06:43 INFO - 'HOME': '/home/cltbld', 15:06:43 INFO - 'LANG': 'en_US.UTF-8', 15:06:43 INFO - 'LANGUAGE': 'en_US:en', 15:06:43 INFO - 'LOGNAME': 'cltbld', 15:06:43 INFO - 'MAIL': '/var/mail/cltbld', 15:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:43 INFO - 'MOZ_NO_REMOTE': '1', 15:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:43 INFO - 'NO_EM_RESTART': '1', 15:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:43 INFO - 'PWD': '/builds/slave/test', 15:06:43 INFO - 'SHELL': '/bin/bash', 15:06:43 INFO - 'SHLVL': '1', 15:06:43 INFO - 'TERM': 'linux', 15:06:43 INFO - 'TMOUT': '86400', 15:06:43 INFO - 'USER': 'cltbld', 15:06:43 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:43 INFO - '_': '/tools/buildbot/bin/python'} 15:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:06:43 INFO - Cleaning up... 15:06:43 INFO - Return code: 0 15:06:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:43 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 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:06:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:06:43 INFO - Using env: {'DISPLAY': ':0', 15:06:43 INFO - 'HOME': '/home/cltbld', 15:06:43 INFO - 'LANG': 'en_US.UTF-8', 15:06:43 INFO - 'LANGUAGE': 'en_US:en', 15:06:43 INFO - 'LOGNAME': 'cltbld', 15:06:43 INFO - 'MAIL': '/var/mail/cltbld', 15:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:43 INFO - 'MOZ_NO_REMOTE': '1', 15:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:43 INFO - 'NO_EM_RESTART': '1', 15:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:43 INFO - 'PWD': '/builds/slave/test', 15:06:43 INFO - 'SHELL': '/bin/bash', 15:06:43 INFO - 'SHLVL': '1', 15:06:43 INFO - 'TERM': 'linux', 15:06:43 INFO - 'TMOUT': '86400', 15:06:43 INFO - 'USER': 'cltbld', 15:06:43 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:43 INFO - '_': '/tools/buildbot/bin/python'} 15:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:43 INFO - Downloading/unpacking blobuploader==1.2.4 15:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:45 INFO - Downloading blobuploader-1.2.4.tar.gz 15:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:06:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:06:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:46 INFO - Downloading docopt-0.6.1.tar.gz 15:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:06:46 INFO - Installing collected packages: blobuploader, requests, docopt 15:06:46 INFO - Running setup.py install for blobuploader 15:06:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:06:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:06:46 INFO - Running setup.py install for requests 15:06:46 INFO - Running setup.py install for docopt 15:06:47 INFO - Successfully installed blobuploader requests docopt 15:06:47 INFO - Cleaning up... 15:06:47 INFO - Return code: 0 15:06:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:47 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 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:06:47 INFO - Using env: {'DISPLAY': ':0', 15:06:47 INFO - 'HOME': '/home/cltbld', 15:06:47 INFO - 'LANG': 'en_US.UTF-8', 15:06:47 INFO - 'LANGUAGE': 'en_US:en', 15:06:47 INFO - 'LOGNAME': 'cltbld', 15:06:47 INFO - 'MAIL': '/var/mail/cltbld', 15:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:47 INFO - 'MOZ_NO_REMOTE': '1', 15:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:47 INFO - 'NO_EM_RESTART': '1', 15:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:47 INFO - 'PWD': '/builds/slave/test', 15:06:47 INFO - 'SHELL': '/bin/bash', 15:06:47 INFO - 'SHLVL': '1', 15:06:47 INFO - 'TERM': 'linux', 15:06:47 INFO - 'TMOUT': '86400', 15:06:47 INFO - 'USER': 'cltbld', 15:06:47 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:47 INFO - '_': '/tools/buildbot/bin/python'} 15:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:06:47 INFO - Running setup.py (path:/tmp/pip-Rl17Yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:06:47 INFO - Running setup.py (path:/tmp/pip-QOZI64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:06:47 INFO - Running setup.py (path:/tmp/pip-3HlNRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:06:47 INFO - Running setup.py (path:/tmp/pip-CWu1bZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:06:47 INFO - Running setup.py (path:/tmp/pip-cdkWkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:06:47 INFO - Running setup.py (path:/tmp/pip-Xhoty_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:06:47 INFO - Running setup.py (path:/tmp/pip-XjjrdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:06:47 INFO - Running setup.py (path:/tmp/pip-DtD0eS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:06:47 INFO - Running setup.py (path:/tmp/pip-59JyOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:06:47 INFO - Running setup.py (path:/tmp/pip-llrKZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:06:48 INFO - Running setup.py (path:/tmp/pip-PjEAur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:06:48 INFO - Running setup.py (path:/tmp/pip-mo9YkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:06:48 INFO - Running setup.py (path:/tmp/pip-v3Cxqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:06:48 INFO - Running setup.py (path:/tmp/pip-8Dy_8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:48 INFO - Running setup.py (path:/tmp/pip-ObqAg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:06:48 INFO - Running setup.py (path:/tmp/pip-H8s3bT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:06:48 INFO - Running setup.py (path:/tmp/pip-SyI_Zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:06:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:06:48 INFO - Running setup.py install for manifestparser 15:06:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:06:48 INFO - Running setup.py install for mozcrash 15:06:48 INFO - Running setup.py install for mozdebug 15:06:48 INFO - Running setup.py install for mozdevice 15:06:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:06:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:06:48 INFO - Running setup.py install for mozfile 15:06:49 INFO - Running setup.py install for mozhttpd 15:06:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Running setup.py install for mozinfo 15:06:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Running setup.py install for mozInstall 15:06:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Running setup.py install for mozleak 15:06:49 INFO - Running setup.py install for mozlog 15:06:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Running setup.py install for moznetwork 15:06:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Running setup.py install for mozprocess 15:06:49 INFO - Running setup.py install for mozprofile 15:06:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:06:49 INFO - Running setup.py install for mozrunner 15:06:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:06:50 INFO - Running setup.py install for mozscreenshot 15:06:50 INFO - Running setup.py install for moztest 15:06:50 INFO - Running setup.py install for mozversion 15:06:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:06:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:06:50 INFO - Cleaning up... 15:06:50 INFO - Return code: 0 15:06:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:50 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 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:06:50 INFO - Using env: {'DISPLAY': ':0', 15:06:50 INFO - 'HOME': '/home/cltbld', 15:06:50 INFO - 'LANG': 'en_US.UTF-8', 15:06:50 INFO - 'LANGUAGE': 'en_US:en', 15:06:50 INFO - 'LOGNAME': 'cltbld', 15:06:50 INFO - 'MAIL': '/var/mail/cltbld', 15:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:50 INFO - 'MOZ_NO_REMOTE': '1', 15:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:50 INFO - 'NO_EM_RESTART': '1', 15:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:50 INFO - 'PWD': '/builds/slave/test', 15:06:50 INFO - 'SHELL': '/bin/bash', 15:06:50 INFO - 'SHLVL': '1', 15:06:50 INFO - 'TERM': 'linux', 15:06:50 INFO - 'TMOUT': '86400', 15:06:50 INFO - 'USER': 'cltbld', 15:06:50 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:50 INFO - '_': '/tools/buildbot/bin/python'} 15:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:06:50 INFO - Running setup.py (path:/tmp/pip-DbY_GI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:06:50 INFO - Running setup.py (path:/tmp/pip-3i2dbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:06:50 INFO - Running setup.py (path:/tmp/pip-P0kpCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:06:50 INFO - Running setup.py (path:/tmp/pip-8TwthF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:06:50 INFO - Running setup.py (path:/tmp/pip-D_VL2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:06:50 INFO - Running setup.py (path:/tmp/pip-AcI7oU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:06:50 INFO - Running setup.py (path:/tmp/pip-3EHjTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:06:51 INFO - Running setup.py (path:/tmp/pip-JhKjyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:06:51 INFO - Running setup.py (path:/tmp/pip-DAmbRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:06:51 INFO - Running setup.py (path:/tmp/pip-sCWl9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:06:51 INFO - Running setup.py (path:/tmp/pip-mfNuzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:06:51 INFO - Running setup.py (path:/tmp/pip-Cr6FHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:06:51 INFO - Running setup.py (path:/tmp/pip-fAlIll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:06:51 INFO - Running setup.py (path:/tmp/pip-9dKcsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:51 INFO - Running setup.py (path:/tmp/pip-FaUk6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:06:51 INFO - Running setup.py (path:/tmp/pip-iu1u0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:06:51 INFO - Running setup.py (path:/tmp/pip-hblTGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:06:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:06:53 INFO - Downloading blessings-1.6.tar.gz 15:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:06:53 INFO - Installing collected packages: blessings 15:06:53 INFO - Running setup.py install for blessings 15:06:53 INFO - Successfully installed blessings 15:06:53 INFO - Cleaning up... 15:06:53 INFO - Return code: 0 15:06:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:06:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:06:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:06:54 INFO - Reading from file tmpfile_stdout 15:06:54 INFO - Current package versions: 15:06:54 INFO - argparse == 1.2.1 15:06:54 INFO - blessings == 1.6 15:06:54 INFO - blobuploader == 1.2.4 15:06:54 INFO - docopt == 0.6.1 15:06:54 INFO - functools32 == 3.2.3-2 15:06:54 INFO - jsonschema == 2.5.1 15:06:54 INFO - manifestparser == 1.1 15:06:54 INFO - mozInstall == 1.12 15:06:54 INFO - mozcrash == 0.17 15:06:54 INFO - mozdebug == 0.1 15:06:54 INFO - mozdevice == 0.48 15:06:54 INFO - mozfile == 1.2 15:06:54 INFO - mozhttpd == 0.7 15:06:54 INFO - mozinfo == 0.9 15:06:54 INFO - mozleak == 0.1 15:06:54 INFO - mozlog == 3.3 15:06:54 INFO - moznetwork == 0.27 15:06:54 INFO - mozprocess == 0.23 15:06:54 INFO - mozprofile == 0.28 15:06:54 INFO - mozrunner == 6.12 15:06:54 INFO - mozscreenshot == 0.1 15:06:54 INFO - mozsystemmonitor == 0.3 15:06:54 INFO - moztest == 0.7 15:06:54 INFO - mozversion == 1.4 15:06:54 INFO - psutil == 3.1.1 15:06:54 INFO - requests == 1.2.3 15:06:54 INFO - wsgiref == 0.1.2 15:06:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:54 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 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:06:54 INFO - Using env: {'DISPLAY': ':0', 15:06:54 INFO - 'HOME': '/home/cltbld', 15:06:54 INFO - 'LANG': 'en_US.UTF-8', 15:06:54 INFO - 'LANGUAGE': 'en_US:en', 15:06:54 INFO - 'LOGNAME': 'cltbld', 15:06:54 INFO - 'MAIL': '/var/mail/cltbld', 15:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:54 INFO - 'MOZ_NO_REMOTE': '1', 15:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:54 INFO - 'NO_EM_RESTART': '1', 15:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:54 INFO - 'PWD': '/builds/slave/test', 15:06:54 INFO - 'SHELL': '/bin/bash', 15:06:54 INFO - 'SHLVL': '1', 15:06:54 INFO - 'TERM': 'linux', 15:06:54 INFO - 'TMOUT': '86400', 15:06:54 INFO - 'USER': 'cltbld', 15:06:54 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:54 INFO - '_': '/tools/buildbot/bin/python'} 15:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:06:54 INFO - Cleaning up... 15:06:54 INFO - Return code: 0 15:06:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:06:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc10030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe3f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, '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': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:06:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:06:54 INFO - Using env: {'DISPLAY': ':0', 15:06:54 INFO - 'HOME': '/home/cltbld', 15:06:54 INFO - 'LANG': 'en_US.UTF-8', 15:06:54 INFO - 'LANGUAGE': 'en_US:en', 15:06:54 INFO - 'LOGNAME': 'cltbld', 15:06:54 INFO - 'MAIL': '/var/mail/cltbld', 15:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:54 INFO - 'MOZ_NO_REMOTE': '1', 15:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:54 INFO - 'NO_EM_RESTART': '1', 15:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:06:54 INFO - 'PWD': '/builds/slave/test', 15:06:54 INFO - 'SHELL': '/bin/bash', 15:06:54 INFO - 'SHLVL': '1', 15:06:54 INFO - 'TERM': 'linux', 15:06:54 INFO - 'TMOUT': '86400', 15:06:54 INFO - 'USER': 'cltbld', 15:06:54 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:54 INFO - '_': '/tools/buildbot/bin/python'} 15:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:06:54 INFO - Cleaning up... 15:06:54 INFO - Return code: 0 15:06:54 INFO - Running post-action listener: _resource_record_post_action 15:06:54 INFO - Running post-action listener: _start_resource_monitoring 15:06:54 INFO - Starting resource monitoring. 15:06:54 INFO - [mozharness: 2016-09-01 22:06:54.613011Z] Finished create-virtualenv step (success) 15:06:54 INFO - [mozharness: 2016-09-01 22:06:54.613435Z] Running install step. 15:06:54 INFO - Running pre-action listener: _resource_record_pre_action 15:06:54 INFO - Running main action method: install 15:06:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:06:54 INFO - Reading from file tmpfile_stdout 15:06:54 INFO - Detecting whether we're running mozinstall >=1.0... 15:06:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:06:54 INFO - Reading from file tmpfile_stdout 15:06:54 INFO - Output received: 15:06:54 INFO - Usage: mozinstall [options] installer 15:06:54 INFO - Options: 15:06:54 INFO - -h, --help show this help message and exit 15:06:54 INFO - -d DEST, --destination=DEST 15:06:54 INFO - Directory to install application into. [default: 15:06:54 INFO - "/builds/slave/test"] 15:06:54 INFO - --app=APP Application being installed. [default: firefox] 15:06:54 INFO - mkdir: /builds/slave/test/build/application 15:06:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:07:10 INFO - Reading from file tmpfile_stdout 15:07:10 INFO - Output received: 15:07:10 INFO - /builds/slave/test/build/application/firefox/firefox 15:07:10 INFO - Running post-action listener: _resource_record_post_action 15:07:10 INFO - [mozharness: 2016-09-01 22:07:10.918185Z] Finished install step (success) 15:07:10 INFO - [mozharness: 2016-09-01 22:07:10.918460Z] Running run-tests step. 15:07:10 INFO - Running pre-action listener: _resource_record_pre_action 15:07:10 INFO - Running main action method: run_tests 15:07:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:07:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:07:10 INFO - Python 2.7.3 15:07:10 INFO - Return code: 0 15:07:10 INFO - grabbing minidump binary from tooltool 15:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:07:10 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 0xece570>, '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 0xecf470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xecf900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:07:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:07:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:07:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:07:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:07:10 INFO - Return code: 0 15:07:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:07:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:07:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:07:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:07:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:07:10 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:07:10 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:07:10 INFO - Using env: {'DISPLAY': ':0', 15:07:10 INFO - 'HOME': '/home/cltbld', 15:07:10 INFO - 'LANG': 'en_US.UTF-8', 15:07:10 INFO - 'LANGUAGE': 'en_US:en', 15:07:10 INFO - 'LOGNAME': 'cltbld', 15:07:10 INFO - 'MAIL': '/var/mail/cltbld', 15:07:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:07:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:11 INFO - 'MOZ_NO_REMOTE': '1', 15:07:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:07:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:11 INFO - 'NO_EM_RESTART': '1', 15:07:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:11 INFO - 'PWD': '/builds/slave/test', 15:07:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:07:11 INFO - 'SHELL': '/bin/bash', 15:07:11 INFO - 'SHLVL': '1', 15:07:11 INFO - 'TERM': 'linux', 15:07:11 INFO - 'TMOUT': '86400', 15:07:11 INFO - 'USER': 'cltbld', 15:07:11 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412', 15:07:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:11 INFO - '_': '/tools/buildbot/bin/python'} 15:07:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:07:11 INFO - mozversion application_buildid: 20160901120620 15:07:11 INFO - mozversion application_changeset: 97d651e84739f5fadf1c39604ce3235122549176 15:07:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:07:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:07:11 INFO - mozversion application_name: Firefox 15:07:11 INFO - mozversion application_remotingname: firefox-dev 15:07:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:07:11 INFO - mozversion application_vendor: Mozilla 15:07:11 INFO - mozversion application_version: 50.0a2 15:07:11 INFO - mozversion platform_buildid: 20160901120620 15:07:11 INFO - mozversion platform_changeset: 97d651e84739f5fadf1c39604ce3235122549176 15:07:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:07:11 INFO - mozversion platform_version: 50.0a2 15:07:11 INFO - using testdate: 1472767631 15:07:11 INFO - actual date: 1472767631 15:07:11 INFO - starting webserver on 'localhost:59722' 15:07:11 INFO - SUITE-START | Running 2 tests 15:07:11 INFO - TEST-START | dromaeo_css 15:07:11 INFO - operating with platform_type : linux_ 15:07:11 INFO - Initialising browser for dromaeo_css test... 15:07:11 INFO - TEST-INFO | started process 26536 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2E2ynC/profile http://localhost:59722/getInfo.html) 15:07:15 INFO - PROCESS | 26536 | __metrics Screen width/height:1600/1200 15:07:15 INFO - PROCESS | 26536 | colorDepth:24 15:07:15 INFO - PROCESS | 26536 | Browser inner width/height: 1024/697 15:07:15 INFO - PROCESS | 26536 | __metrics 15:07:16 ERROR - PROCESS | 26536 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 15:07:17 INFO - TEST-INFO | 26536: exit 0 15:07:17 INFO - Browser initialized. 15:07:17 INFO - Running cycle 1/1 for dromaeo_css test... 15:07:17 INFO - TEST-INFO | started process 26663 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2E2ynC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:07:28 INFO - PROCESS | 26663 | RSS: Main: 152563712 15:07:28 INFO - PROCESS | 26663 | 15:08:18 INFO - PROCESS | 26663 | 1472767698475 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:10:26 INFO - PROCESS | 26663 | Cycle 1(1): loaded http://localhost:59722/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59722/tests/dromaeo/cssquery-ext.html) 15:10:28 INFO - PROCESS | 26663 | RSS: Main: 159088640 15:10:28 INFO - PROCESS | 26663 | 15:13:30 INFO - PROCESS | 26663 | Cycle 1(1): loaded http://localhost:59722/tests/dromaeo/cssquery-ext.html (next: http://localhost:59722/tests/dromaeo/cssquery-jquery.html) 15:13:30 INFO - PROCESS | 26663 | RSS: Main: 153755648 15:13:30 INFO - PROCESS | 26663 | 15:16:32 INFO - PROCESS | 26663 | Cycle 1(1): loaded http://localhost:59722/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59722/tests/dromaeo/cssquery-mootools.html) 15:16:33 INFO - PROCESS | 26663 | RSS: Main: 155234304 15:16:33 INFO - PROCESS | 26663 | 15:19:34 INFO - PROCESS | 26663 | Cycle 1(1): loaded http://localhost:59722/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59722/tests/dromaeo/cssquery-prototype.html) 15:19:35 INFO - PROCESS | 26663 | RSS: Main: 152752128 15:19:35 INFO - PROCESS | 26663 | 15:22:37 INFO - PROCESS | 26663 | Cycle 1(1): loaded http://localhost:59722/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59722/tests/dromaeo/cssquery-yui.html) 15:22:38 INFO - PROCESS | 26663 | RSS: Main: 155430912 15:22:38 INFO - PROCESS | 26663 | 15:25:40 INFO - PROCESS | 26663 | Cycle 1(1): loaded http://localhost:59722/tests/dromaeo/cssquery-yui.html (next: http://localhost:59722/tests/dromaeo/cssquery-dojo.html) 15:25:41 INFO - PROCESS | 26663 | RSS: Main: 156561408 15:25:41 INFO - PROCESS | 26663 | 15:25:41 INFO - PROCESS | 26663 | __start_tp_report 15:25:41 INFO - PROCESS | 26663 | _x_x_mozilla_page_load 15:25:41 INFO - PROCESS | 26663 | _x_x_mozilla_page_load_details 15:25:41 INFO - PROCESS | 26663 | |i|pagename|runs| 15:25:41 INFO - PROCESS | 26663 | |0;dojo.html;3282.031800618817;3298.2084299768057;3311.106001379629;3322.1694576355912;3329.583802024747;5755.6258843175165;5799.594028418007;5817.592768506201;5825.213596164516;5853.385394533577;5785.971070144644;5856.033754430525;5870.26621672291;5871.412858714127;5896.675682837442;5289.973550132254;5327.147656374981;5337.039332920072;5365.114756065264;5392.487713667196;5222.086134926391;5222.399424066243;5270.446603106657;5305.336832895888;5333.706646134461;5314.973425132888;5348.117560602496;5351.117065684158;5351.384590772072;5381.623286369956;5402.540784033342;5406.216098665672;5406.675599464044;5427.3215848019;5435.864103397446;5056.898862022776;5088.643794934356;5096.719680417576;5290.338707661543;5291;5690.06113991191;5724.055530837407;5730.054541000772;5743.856403589902;5797.681127537984;6127.172831667757;6169.321374648785;6184.56708030438;6190.0405437157315;6195.81412557624;5942.108683697436;6110.847228819271;6119.380693981191;6184.81445556634;6214.65819379934;5805.970970145122;5811.186433899257;5837.883242335172;5844.444777746107;5847.152162936393;6324.620522768649;6346.460550853137;6351.587146835441;6363.96818015907;6379.330170332141;5873.58884878063;6013.51891848651;6018.127371531104;6053.848653783691;6091.269047714302;6219.315875253718;6220.129181914535;6249.687515624291;6250.1874756281395;6251.74993000284;6207.6275423474735;6213.502919766408;6230.6573138476915;6232.158658581034;6239.563230573907;6043.637381757108;6116.944513560587;6127.264728232641;6222.097795819581;6223.937760622426;4704.1767690654;4715.7642117893965;4730.929036064462;4742.976285118552;4772.904541909212;4205.621494065549;4222.239996800544;4236.279832428495;4238.385434112037;4241.554636763157;5792.304923409218;5905.232319798399;5917.615355001911;5920.111983202468;6007.309159446632;6247.219097612799;6262.373762623702;6316.147320111818;6339.904901426482;6351.110844481776;5999.850003749942;6048.879022419527;6052.697365131726;6056.697165141725;6060.727267272983;5041.521055499722;5139.871503212449;5169.06956747789;5233.607479439057;5257.6056795739705;6435.163428754232;6471.546991710629;6491.961286194186;6492.513061520311;6493.51298652603;6285.748570057147;6289.622622642656;6297.590656607397;6308.968455157878;6309.305976342507;6237.064440333805;6355.205599300088;6375.394337537927;6456.483481321389;6508.60948343101;6193.907091393723;6205.100260462298;6205.875882482415;6212.627242365292;6218.471429928326;6340.080688300263;6348.650824204528;6350.745970161327;6353.14232578596;6358.618482891041;5898.233229680114;6037.9735444973585;6111.908321375272;6208.72060757258;6215.65813880241;6107.97078753358;6121.056693971993;6125.876495711744;6139.96930015365;6151.477124444473;5920.348761636131;6032.155498230339;6079.635221886701;6079.908801368071;6115.266168059861;5973.223480947623;6003.219581454383;6115.3578874217565;6140.87237156979;6212.161358216764;6080.939190608037;6086.391360863879;6088.847778805565;6108.51131909455;6108.72510737009 15:25:41 INFO - PROCESS | 26663 | |1;ext.html;584.1354794903542;633.5881676910008;646.9611823290602;681.1349586025748;683.135833171039;40202.396964045525;44046.89882752929;44142.33786493197;44147.33778993314;44229;45244.77377613116;45773;45917.31124033142;45945.77027114868;45949.7702511487;9057.818843623123;9196.724098277062;9232.169104780569;9293.58178881952;9314.72055838326;9071.682491112811;9241.907580924171;9243.861342079841;9247.167754902057;9247.630094796234;9230.86153707695;9289.117533834275;9342.532873356306;9346.953265233664;9388.202002829768;9240.260779137687;9263.583138758739;9271.814563708756;9320.533973301342;9324.720258392259;9165;9187.67843125494;9257.444553326823;9292.442453452815;9308.767280817967;43861.342079868824;43896.12207755827;44288.3356749649;44299.778501107285;44366.556334436566;3970.781417937198;4135.069609337905;4138.565450627701;4197.328427451624;4784.425868895755;35398.646013539794;35486.11284717877;35696.821515892516;36815.81592092022;36966.260674786354;4740.810367585293;4756.31033500144;4868.099401610678;4900.436449808247;4952.900941981176;375080;375599;375685;377371.1131444353;380124;38094.428583571265;65795.34204657892;65852.34147658462;66107.66946165239;66492;38728.419073713914;40535.797321013204;40694.59305406908;40961;41340.79329603333;17300.65398692019;17493.212805423787;17597;17643.382481613084;17699.20353584092;13535.729285414236;13615.455381784815;13627.454901804014;13649;13659.726805464034;9146.634134634673;9224.123708247706;9247.953760231156;9291.256699464027;9372.906270937203;3543.94684079739;3589.2821435712963;3614;3618.2401695644207;3641.217138315275;1114.6067415730338;1145.66202970124;1174.1839421602076;1227.846519185102;1263.5135472843021;34169.65830341714;34727;34809.47785783215;34956;35468.46797298043;1199.2431088455455;1241.8571864235548;1253.849538055421;1295.1387327427155;1296.7406518696298;17473.7378939316;18201.180946857425;18212.90551586558;18312.81687183138;18725.719114213298;578379;581512;593250;595747;598123;41930.580694192664;42558.36162457566;42561.78719106447;42631.786841065594;42699.7865010673;1470.1693543148087;1498.2883130513137;1500.9399624015136;1506.8945173837944;1507.2915729607066;33151.83424082864;33482.162945926546;33623.83188084044;33826;34186.65813341835;55035.44964550303;56366;56659.716701416226;56839.147412789665;56853;382274.5317640475;383120;384078;384203;385817;31179.37641247117;31203.531947020358;31239.68760312459;31244.531332029583;31617.20956976094;52164.7391763054;52843;53729;53835;54540;44878.77560612176;45886.541134588224;45900.77049614731;46139;47679.76160119177;44658;45811.3128303069;46225;46581.3012804815;47820.521794781605;39975.400368993905;43682.126357472036;45604.315935261664;45942.770286148356;46210.306845398016;38702.35685186484;39460.40809387919;40090;40142.397864032646;40861.795691021354;31023.379532409675;31139.68860311368;31329;31451.213719657193;31707.841460792548 15:25:41 INFO - PROCESS | 26663 | |2;jquery.html;412.13040484577556;416.1199063979684;422.91753108143917;434.53938824845665;441.72171531934896;19064.23743050278;19988.60022799545;20543.48641283967;21150;21172.25897093602;20649.070791814367;20779.064942077606;20916.26793062243;21559.1376344946;21752;5948.583599148062;6175.197224360849;6179.5674302798825;6182.258129024501;6182.814515564541;5620.381758006616;5843.590948633598;5898.675572843492;6028.367021462749;6083.17877086592;5825.126231065332;5869.618474799146;5913.379095195007;5929.466348028676;5971;5774.133879918025;5894.941050589482;5912.231409916726;5961.791337303195;5963.045912653998;5864.413558644144;6060.454559089659;6069.514438844882;6072.908906366408;6080.878382432326;16129.596760080974;24360.147394841097;24769.00923963039;24769.380765481008;24822.751772482225;2428.4293191100255;2437.7074751029813;2488.216211893248;2491.869451618876;2994.3412449261127;10257.794844103077;10672.573097076107;10734.677959661223;10739.140868730483;10789.730256743565;1988.6519859024756;2054.732884724991;2080.2615071649498;2083.1771450277206;2084.093419362582;82850;83914;86924.56537717271;87372.56313718454;89437.10562894352;11525.423728813694;11854.407279635983;11855.46650400734;11872.643820685393;11889.940550297193;16820.41128560519;16841.157942102847;16841.747373789403;16842.15789210559;16967.49097527076;8540.871886921701;8573.957130214434;8577.95711021441;8751.868721969175;9115.954420227856;8852.911470885338;8879.067697891758;9041.77395565103;9119.544022798833;9223.216026637676;4873.269009648581;4931.358923340014;5116.7624705076805;5145.639805213599;5265.315508983807;1948.5420926082359;1958.745363102816;2053.414776788608;2177.227084385052;2197.2748992832326;1014.2301683562231;1044.6291566493785;1055.8205105132147;1097.6926460591046;1139.4701463819363;20280.08739606721;20921.581568368547;21258.36224913255;21440;21917.452063698536;1073.608133031438;1090.2967585907045;1105.077260487502;1135.239389608964;1148.471703016605;11858.407079645984;12149.331786751813;12166.513339466499;12184.329861857683;12336.198147120409;542252;573805;578282;581721;586332;88009;89726.55136724275;96889;99558.50220748995;100777;1066.973325666849;1075.6014088582506;1102.614085070219;1119.0488085127577;1120.5293776613842;18513.44459666212;19077.523061923563;19372;19417.708734368713;19631.214751409785;43886.34170487509;44284.33573496336;44319.11361772811;44593.55406445894;45351.546484536055;58149;61333.69333153484;64061.35938640741;64432.67783661051;65666.34333656602;8472.83054338922;9197.86203206939;9198;9200.26397888154;9445.905540944503;11823.940880295544;14038.78941815853;14150.78773818373;14170.433182672577;14716;12295.815562766387;12488.562900298446;12629.3685315732;12670.87329126697;12697.746045079231;11742.765144696887;12181.269123852597;12232.143749937775;12413.193142445713;12922.353882306035;11904.761904761683;12267.263964161823;12566.434510446868;12570.245785252913;12739.490420383081;8983.550822458721;9263.44419334815;9271.814563708553;9386.108319709616;9429.85839782982;10702.357858528514;11786.705332366759;12356.320402377956;12643.936780316408;12644.114911956074 15:25:41 INFO - PROCESS | 26663 | |3;mootools.html;1555.64997875478;1578.2739939627775;1755.2891079112965;1819.490542648059;1860.0699650174913;8364.665413383473;8934.955325223365;9032.638694452222;9791.314607977445;9803.411795292279;9192.724218273446;9501.144896959273;9509.191718703902;9574.04259574044;9617.422954622727;2456.263121063678;2482.8758562071826;2483.900643974248;2489.0666000249907;2516.0816302049802;2442.1086303499274;2496.900123995047;2499;2500.237427584586;2502.637117617944;2449.9265022049367;2456.9312349128186;2484.527939691462;2501.062101711858;2512.811539134563;2427.6965379327585;2429.3926518370495;2434.050720219116;2454.840435371695;2470.0737223541173;2419.63705444183;2449.9755002449924;2456.766607172334;2463.8152138589676;2464.728879823218;9582.616695332179;9647.855282170704;9652.22782177424;9655.937846836843;9663.806723865484;5499.175123731432;5590.077637189859;5604.915926261109;5606.21512988182;5606.607537472379;3531.399662057456;3622.6920711739535;3664.395374763161;3683.0239986403626;3688.8708895188806;1151.5681619392728;1172.132621859826;1177.6290468502393;1191.6544202181358;1195.4680167325544;12403.317817520032;12428.62714118578;12478.064145189148;12519.998400128059;12535.811962820566;4721.655494025053;4728.3143944127905;4802.951970480251;4894.339264199359;4896.5837903777865;10604.89395106039;10792.51433685486;10802.189835762349;10857.022867941923;10899.346039237671;5568.192612071227;5581.6371935824045;5595.992721310122;5603.971980140073;5608.46719561641;4612.654050946193;4983.875403114951;5144.279800827887;5196.428392876781;5213.7914483421;4330.090680957034;4408.977955110268;4424.11517696462;4473.351364052194;4485.587040082363;1389.270632917706;1410.4358256697199;1424.8503907089814;1429.1567974894863;1438.5900018494679;968.375397868371;1028.7273872423814;1115.3642423818508;1117.4412793603199;1127.9605213817476;6606.306337834554;6728.730850766013;6757;6846.931530684629;6849.691763870638;469.4062011555385;487.8316980641673;490.443346801383;493.73338397265076;495.04950495049593;4728.95271047292;4834.154023045953;4946.480619534969;4950.405951285797;4966;11365.204435689588;11812.645620631394;11930;11968.162228644085;11975.940120299518;11387.430628468543;11608.477618507188;11736.706582335339;11801.822972655416;11866.940665296619;8543.572821358906;8671.21959023691;8741.907261592301;8744.7376578704;8757.47455152693;6772.729090836311;6848.7260509579055;6878.518503704692;6902.792916212522;6926.376626103674;10386.11718003978;10526.473676316;10674.35953842772;10753.677389678322;10856.728581785519;10757.892421076001;10879.782404352027;10886.836697449702;11165.497552610315;11207.887921120684;7356.264373562601;8104.068032176139;8564.05795362522;8598.441101328393;8724.65101395937;9378.812423751351;9417.576209070745;9452.480113593818;9472.194863436409;9548.045195480396;8323.875141872755;8507.574621269036;8510.574471276535;8555.486670799772;8559.657613695383;8287.378446616649;8354.331653467829;8364.749057528285;8454.830903381775;8457.112003239818;7882.802929926568;7909.960450197712;7921.564313962787;7933.690940994671;8004.519728816057;5847.210626565359;5900.793472228452;5990.880182396415;6016.789412370546;6101.054336577838;5870.26621672291;5942.524598032233;5977.193078934288;5980.730867110903;6001.639901605918 15:25:41 INFO - PROCESS | 26663 | |4;prototype.html;344.5916588842222;348.6931500279753;350.24696901661434;356.7057177828289;357.456665867881;5803.941960580393;5808.56435767317;5932.932072226998;5963.731632076556;5971.820845374646;5764.51001664859;5792.82621521355;5793.159991801186;5819.243498345219;5846.649201047941;4600.907981840378;4632.911265852514;4671.182543054969;4673.789679464432;4681.204195286809;4453.153900758862;4585.403897493338;4610.907781844361;4621;4680.391549098629;4557.156925968707;4572.862814115582;4580.931286030712;4593.54245478669;4624.260118381059;4338.262495375794;4594.264917613183;4635.976820115911;4647.512011238805;4649.674522783406;3939.9015024624327;3949.2299001694682;3971.6822654187886;3974.503187101612;3975.304321743712;5658;5695.629784064023;5720.3135623725;5751.942480575182;5772.422757724236;5139.794408223666;5194.6104042196985;5239.659422137549;5260.631755777097;5263.6578622389425;5995.880082398371;6071.605345652562;6074.906516826969;6100.084987251903;6106.236720409949;4391.516933137353;4405.273129933557;4431.933520997188;4446.866594002185;4460.375547423363;6188.814335569895;6310.905336419957;6369.426751592333;6391.137196478462;6398.808035758934;4457.576530229623;4459.509453960061;4464.151811155935;4482.327650852333;4515.932261016087;4564.269716845289;4568.17772800899;4571.9314210286875;4578.725276483422;4585.839495617637;4980.551750342486;5199.844004679866;5211.8175863845345;5230.92153617696;5233.973830130901;5948.691287916651;6065.211522502046;6080.908786368208;6109.175261339662;6109.389061093855;1552.2937063636018;1567.2242240091227;1572.1353255709314;1578.2187817030642;1582.8575428211286;969.4038166527675;989.1592146675268;1005.1707341443339;1015.7105225010837;1017.1354348803605;5253.658512196695;5255;5284.912459578445;5290.444503327172;5324.41431442541;5524.530414914777;5572.969000734877;5579.2746942897165;5607.831765047055;5611.334616057422;5036.521530454601;5266.708307307933;5318.468153184728;5340.519353258225;5356.276902618175;2949.41011797637;2989.50673138934;3024.3668419053906;3030.2424393901524;3035.408095421416;5720.541261978169;5740.0815869460685;5761.135829625589;5777.382332946781;5785.50823180026;6036.698165091728;6369.20384951881;6477.77327793525;6478.481721462272;6495.805125846232;6512.120863683342;6606.735730570724;6625.701843417058;6636.113290718553;6666.500012499082;6072;6201.627902325875;6303.684815759102;6323.336049714714;6342.682865856596;6291.483406637381;6304.558680892477;6313.747450101944;6373.107764912823;6383.585066970632;6323.272823625158;6403.29563747978;6565.310642382481;6593.208814942238;6679;4459.910801783882;4496.7301961882395;4518.548145185455;4544.931826022546;4568.725876447424;6156;6258.780942666985;6452.096706461005;6454.99947508137;6472.223333200046;6174.691265436621;6192.783252586138;6208.255009398901;6214.104698067133;6225.875482490234;6034.2155519782145;6161.39803651045;6167.5682702210415;6175.197224360984;6219.782307619212;6117.510599152146;6132.63204207749;6141.785037523665;6146.385361463817;6146.739918316645;6207.317195108625;6241.531885108726;6241.656708881055;6253.937460625336;6319.9052014218905;5785.623934444247;5999.4900433463645;6022.524481501885;6044.184035155374;6059.878802424015 15:25:41 INFO - PROCESS | 26663 | |5;yui.html;819.7499762572413;819.893413856199;824.2787560884228;865.4417900454205;929;15362.539123826275;16210.918945405272;17691.11544422777;18193.27226910922;18327.450176494727;16463.094529800856;16523.83476165238;16635.50093497197;16832.579185520368;16895.662086758257;752.7026824404358;890.5146695051212;894.8389289927827;909.5725009245645;913.5751875377948;893.0266010049045;902.5848109869468;903.7198468474794;904.1997831918767;904.2449554621866;967.0281367225928;970.1317320997703;971.3977334052897;973.274910191908;974.8878878928907;949.4255975135047;951.3673407184283;955.9904400955971;961.5336561767538;962.5235508423306;703.5813690853934;703.7325816189866;709.0427922304899;709.4927127104145;709.8225443639091;15873.047617142893;16126.838731612652;16175.35298588055;16203.594910127224;16395.672086558323;15971.041737495787;16493.257803398756;16809.495715128567;16987.3205071797;17108.914455427766;13581;13612.251326177056;13623.863761362358;13647.590572282847;14044.297785110704;4090.734102283342;4137.710360274782;4154.5222299435645;4158.106007208435;4164.646005089541;85624.71562926561;87553.56223218843;87868;89202.55398723029;92239.53880230556;13390;13498.730025399633;13534.86465135336;13581.185128892299;13707.451701931812;25373.238802835946;25481.235562932772;25534.23397298084;25690.229293121236;25860.35349116287;8580.35647326453;8985.730428087169;9047.864282035775;9082.772930676785;9242.86135707965;4893;5037.79848806051;5053.974730126326;5125.692458452505;5255.02781985334;3637.2725454909123;3714.220013797079;3724.5344331958504;3747.34421476246;3753.305638456894;617.0528239152878;618.8576627375638;620.2247191011236;639.4820195641545;645.964471954047;678.9321067893171;771.248033167657;800.0519384529351;833.5748768128177;860.0840105287848;10418.961974808628;10571.629992950364;10835.295705779099;11060.668179954615;11297.604583839526;785.1363500149904;801.5951944268223;818.6602559937678;831.9251267385964;839.5004972041626;5509.559235261169;5568.081266591048;5589.441055894378;5632.464915832989;5652.689102099342;126997;132474.33762831317;135204;135917;137042;109505.45247273713;109945.45027274813;111626.44186779014;112534;113136.43431782788;57383.426165738645;57606.13590796141;57925;74246;93483.0651693488;10180.185585153318;10559;10559.736006599896;10698.090662293482;10708.518116684923;58713.706431467566;58796.706016469645;59424.70287648534;59712;60193.39806601878;83259;85891;87390;90050.54974725084;90256;13635.250061246727;13955.511557095453;14063.859361406256;14096.295185240902;14106.929465352607;15014.249287535798;15138.318775654901;15182.316795743996;15217.391304348144;15336.923315383352;13433.126846754929;13810.585682429542;14052.718945620825;14180.432782688576;14361.64095897611;14207.28963551839;14467.92766036163;14607.415703371746;14702.411903524167;14774.852251477347;13633.113847599874;13793.86206137966;13891.097078689852;14158.150510969375;14354.641133971734;11385.259958102697;11551;11923.82114268304;11956.820647690465;12303;10923.508442120283;11116.832732562965;11335.263207891461;11335.886641133813;11396.430178490877 15:25:41 INFO - PROCESS | 26663 | __end_tp_report 15:25:41 INFO - PROCESS | 26663 | __start_cc_report 15:25:41 INFO - PROCESS | 26663 | _x_x_mozilla_cycle_collect,345 15:25:41 INFO - PROCESS | 26663 | __end_cc_report 15:25:41 INFO - PROCESS | 26663 | __startTimestamp1472768740999__endTimestamp 15:25:41 INFO - PROCESS | 26663 | 15:25:41 INFO - PROCESS | 26663 | ------- Summary: start ------- 15:25:41 INFO - PROCESS | 26663 | Number of tests: 6 15:25:41 INFO - PROCESS | 26663 | 15:25:41 INFO - PROCESS | 26663 | [#0] dojo.html Cycles:165 Average:5784.95 Median:6048.88 stddev:673.90 (11.1%) stddev-sans-first:646.73 15:25:41 INFO - PROCESS | 26663 | Values: 3282.0 3298.2 3311.1 3322.2 3329.6 5755.6 5799.6 5817.6 5825.2 5853.4 5786.0 5856.0 5870.3 5871.4 5896.7 5290.0 5327.1 5337.0 5365.1 5392.5 5222.1 5222.4 5270.4 5305.3 5333.7 5315.0 5348.1 5351.1 5351.4 5381.6 5402.5 5406.2 5406.7 5427.3 5435.9 5056.9 5088.6 5096.7 5290.3 5291.0 5690.1 5724.1 5730.1 5743.9 5797.7 6127.2 6169.3 6184.6 6190.0 6195.8 5942.1 6110.8 6119.4 6184.8 6214.7 5806.0 5811.2 5837.9 5844.4 5847.2 6324.6 6346.5 6351.6 6364.0 6379.3 5873.6 6013.5 6018.1 6053.8 6091.3 6219.3 6220.1 6249.7 6250.2 6251.7 6207.6 6213.5 6230.7 6232.2 6239.6 6043.6 6116.9 6127.3 6222.1 6223.9 4704.2 4715.8 4730.9 4743.0 4772.9 4205.6 4222.2 4236.3 4238.4 4241.6 5792.3 5905.2 5917.6 5920.1 6007.3 6247.2 6262.4 6316.1 6339.9 6351.1 5999.9 6048.9 6052.7 6056.7 6060.7 5041.5 5139.9 5169.1 5233.6 5257.6 6435.2 6471.5 6492.0 6492.5 6493.5 6285.7 6289.6 6297.6 6309.0 6309.3 6237.1 6355.2 6375.4 6456.5 6508.6 6193.9 6205.1 6205.9 6212.6 6218.5 6340.1 6348.7 6350.7 6353.1 6358.6 5898.2 6038.0 6111.9 6208.7 6215.7 6108.0 6121.1 6125.9 6140.0 6151.5 5920.3 6032.2 6079.6 6079.9 6115.3 5973.2 6003.2 6115.4 6140.9 6212.2 6080.9 6086.4 6088.8 6108.5 6108.7 15:25:41 INFO - PROCESS | 26663 | 15:25:41 INFO - PROCESS | 26663 | [#1] ext.html Cycles:180 Average:61174.78 Median:42665.79 stddev:122369.19 (286.8%) stddev-sans-first:122627.91 15:25:41 INFO - PROCESS | 26663 | Values: 584.1 633.6 647.0 681.1 683.1 40202.4 44046.9 44142.3 44147.3 44229.0 45244.8 45773.0 45917.3 45945.8 45949.8 9057.8 9196.7 9232.2 9293.6 9314.7 9071.7 9241.9 9243.9 9247.2 9247.6 9230.9 9289.1 9342.5 9347.0 9388.2 9240.3 9263.6 9271.8 9320.5 9324.7 9165.0 9187.7 9257.4 9292.4 9308.8 43861.3 43896.1 44288.3 44299.8 44366.6 3970.8 4135.1 4138.6 4197.3 4784.4 35398.6 35486.1 35696.8 36815.8 36966.3 4740.8 4756.3 4868.1 4900.4 4952.9 375080.0 375599.0 375685.0 377371.1 380124.0 38094.4 65795.3 65852.3 66107.7 66492.0 38728.4 40535.8 40694.6 40961.0 41340.8 17300.7 17493.2 17597.0 17643.4 17699.2 13535.7 13615.5 13627.5 13649.0 13659.7 9146.6 9224.1 9248.0 9291.3 9372.9 3543.9 3589.3 3614.0 3618.2 3641.2 1114.6 1145.7 1174.2 1227.8 1263.5 34169.7 34727.0 34809.5 34956.0 35468.5 1199.2 1241.9 1253.8 1295.1 1296.7 17473.7 18201.2 18212.9 18312.8 18725.7 578379.0 581512.0 593250.0 595747.0 598123.0 41930.6 42558.4 42561.8 42631.8 42699.8 1470.2 1498.3 1500.9 1506.9 1507.3 33151.8 33482.2 33623.8 33826.0 34186.7 55035.4 56366.0 56659.7 56839.1 56853.0 382274.5 383120.0 384078.0 384203.0 385817.0 31179.4 31203.5 31239.7 31244.5 31617.2 52164.7 52843.0 53729.0 53835.0 54540.0 44878.8 45886.5 45900.8 46139.0 47679.8 44658.0 45811.3 46225.0 46581.3 47820.5 39975.4 43682.1 45604.3 45942.8 46210.3 38702.4 39460.4 40090.0 40142.4 40861.8 31023.4 31139.7 31329.0 31451.2 31707.8 15:25:41 INFO - PROCESS | 26663 | 15:25:41 INFO - PROCESS | 26663 | [#2] jquery.html Cycles:180 Average:32266.03 Median:12057.36 stddev:94120.46 (780.6%) stddev-sans-first:94354.10 15:25:41 INFO - PROCESS | 26663 | Values: 412.1 416.1 422.9 434.5 441.7 19064.2 19988.6 20543.5 21150.0 21172.3 20649.1 20779.1 20916.3 21559.1 21752.0 5948.6 6175.2 6179.6 6182.3 6182.8 5620.4 5843.6 5898.7 6028.4 6083.2 5825.1 5869.6 5913.4 5929.5 5971.0 5774.1 5894.9 5912.2 5961.8 5963.0 5864.4 6060.5 6069.5 6072.9 6080.9 16129.6 24360.1 24769.0 24769.4 24822.8 2428.4 2437.7 2488.2 2491.9 2994.3 10257.8 10672.6 10734.7 10739.1 10789.7 1988.7 2054.7 2080.3 2083.2 2084.1 82850.0 83914.0 86924.6 87372.6 89437.1 11525.4 11854.4 11855.5 11872.6 11889.9 16820.4 16841.2 16841.7 16842.2 16967.5 8540.9 8574.0 8578.0 8751.9 9116.0 8852.9 8879.1 9041.8 9119.5 9223.2 4873.3 4931.4 5116.8 5145.6 5265.3 1948.5 1958.7 2053.4 2177.2 2197.3 1014.2 1044.6 1055.8 1097.7 1139.5 20280.1 20921.6 21258.4 21440.0 21917.5 1073.6 1090.3 1105.1 1135.2 1148.5 11858.4 12149.3 12166.5 12184.3 12336.2 542252.0 573805.0 578282.0 581721.0 586332.0 88009.0 89726.6 96889.0 99558.5 100777.0 1067.0 1075.6 1102.6 1119.0 1120.5 18513.4 19077.5 19372.0 19417.7 19631.2 43886.3 44284.3 44319.1 44593.6 45351.5 58149.0 61333.7 64061.4 64432.7 65666.3 8472.8 9197.9 9198.0 9200.3 9445.9 11823.9 14038.8 14150.8 14170.4 14716.0 12295.8 12488.6 12629.4 12670.9 12697.7 11742.8 12181.3 12232.1 12413.2 12922.4 11904.8 12267.3 12566.4 12570.2 12739.5 8983.6 9263.4 9271.8 9386.1 9429.9 10702.4 11786.7 12356.3 12643.9 12644.1 15:25:41 INFO - PROCESS | 26663 | 15:25:41 INFO - PROCESS | 26663 | [#3] mootools.html Cycles:180 Average:6267.40 Median:2660.99 stddev:3521.22 (132.3%) stddev-sans-first:3513.30 15:25:41 INFO - PROCESS | 26663 | Values: 1555.6 1578.3 1755.3 1819.5 1860.1 8364.7 8935.0 9032.6 9791.3 9803.4 9192.7 9501.1 9509.2 9574.0 9617.4 2456.3 2482.9 2483.9 2489.1 2516.1 2442.1 2496.9 2499.0 2500.2 2502.6 2449.9 2456.9 2484.5 2501.1 2512.8 2427.7 2429.4 2434.1 2454.8 2470.1 2419.6 2450.0 2456.8 2463.8 2464.7 9582.6 9647.9 9652.2 9655.9 9663.8 5499.2 5590.1 5604.9 5606.2 5606.6 3531.4 3622.7 3664.4 3683.0 3688.9 1151.6 1172.1 1177.6 1191.7 1195.5 12403.3 12428.6 12478.1 12520.0 12535.8 4721.7 4728.3 4803.0 4894.3 4896.6 10604.9 10792.5 10802.2 10857.0 10899.3 5568.2 5581.6 5596.0 5604.0 5608.5 4612.7 4983.9 5144.3 5196.4 5213.8 4330.1 4409.0 4424.1 4473.4 4485.6 1389.3 1410.4 1424.9 1429.2 1438.6 968.4 1028.7 1115.4 1117.4 1128.0 6606.3 6728.7 6757.0 6846.9 6849.7 469.4 487.8 490.4 493.7 495.0 4729.0 4834.2 4946.5 4950.4 4966.0 11365.2 11812.6 11930.0 11968.2 11975.9 11387.4 11608.5 11736.7 11801.8 11866.9 8543.6 8671.2 8741.9 8744.7 8757.5 6772.7 6848.7 6878.5 6902.8 6926.4 10386.1 10526.5 10674.4 10753.7 10856.7 10757.9 10879.8 10886.8 11165.5 11207.9 7356.3 8104.1 8564.1 8598.4 8724.7 9378.8 9417.6 9452.5 9472.2 9548.0 8323.9 8507.6 8510.6 8555.5 8559.7 8287.4 8354.3 8364.7 8454.8 8457.1 7882.8 7910.0 7921.6 7933.7 8004.5 5847.2 5900.8 5990.9 6016.8 6101.1 5870.3 5942.5 5977.2 5980.7 6001.6 15:25:41 INFO - PROCESS | 26663 | 15:25:41 INFO - PROCESS | 26663 | [#4] prototype.html Cycles:180 Average:5100.07 Median:5720.43 stddev:1509.63 (26.4%) stddev-sans-first:1471.06 15:25:41 INFO - PROCESS | 26663 | Values: 344.6 348.7 350.2 356.7 357.5 5803.9 5808.6 5932.9 5963.7 5971.8 5764.5 5792.8 5793.2 5819.2 5846.6 4600.9 4632.9 4671.2 4673.8 4681.2 4453.2 4585.4 4610.9 4621.0 4680.4 4557.2 4572.9 4580.9 4593.5 4624.3 4338.3 4594.3 4636.0 4647.5 4649.7 3939.9 3949.2 3971.7 3974.5 3975.3 5658.0 5695.6 5720.3 5751.9 5772.4 5139.8 5194.6 5239.7 5260.6 5263.7 5995.9 6071.6 6074.9 6100.1 6106.2 4391.5 4405.3 4431.9 4446.9 4460.4 6188.8 6310.9 6369.4 6391.1 6398.8 4457.6 4459.5 4464.2 4482.3 4515.9 4564.3 4568.2 4571.9 4578.7 4585.8 4980.6 5199.8 5211.8 5230.9 5234.0 5948.7 6065.2 6080.9 6109.2 6109.4 1552.3 1567.2 1572.1 1578.2 1582.9 969.4 989.2 1005.2 1015.7 1017.1 5253.7 5255.0 5284.9 5290.4 5324.4 5524.5 5573.0 5579.3 5607.8 5611.3 5036.5 5266.7 5318.5 5340.5 5356.3 2949.4 2989.5 3024.4 3030.2 3035.4 5720.5 5740.1 5761.1 5777.4 5785.5 6036.7 6369.2 6477.8 6478.5 6495.8 6512.1 6606.7 6625.7 6636.1 6666.5 6072.0 6201.6 6303.7 6323.3 6342.7 6291.5 6304.6 6313.7 6373.1 6383.6 6323.3 6403.3 6565.3 6593.2 6679.0 4459.9 4496.7 4518.5 4544.9 4568.7 6156.0 6258.8 6452.1 6455.0 6472.2 6174.7 6192.8 6208.3 6214.1 6225.9 6034.2 6161.4 6167.6 6175.2 6219.8 6117.5 6132.6 6141.8 6146.4 6146.7 6207.3 6241.5 6241.7 6253.9 6319.9 5785.6 5999.5 6022.5 6044.2 6059.9 15:25:41 INFO - PROCESS | 26663 | 15:25:41 INFO - PROCESS | 26663 | [#5] yui.html Cycles:180 Average:22744.10 Median:3675.75 stddev:33133.47 (901.4%) stddev-sans-first:33185.52 15:25:41 INFO - PROCESS | 26663 | Values: 819.7 819.9 824.3 865.4 929.0 15362.5 16210.9 17691.1 18193.3 18327.5 16463.1 16523.8 16635.5 16832.6 16895.7 752.7 890.5 894.8 909.6 913.6 893.0 902.6 903.7 904.2 904.2 967.0 970.1 971.4 973.3 974.9 949.4 951.4 956.0 961.5 962.5 703.6 703.7 709.0 709.5 709.8 15873.0 16126.8 16175.4 16203.6 16395.7 15971.0 16493.3 16809.5 16987.3 17108.9 13581.0 13612.3 13623.9 13647.6 14044.3 4090.7 4137.7 4154.5 4158.1 4164.6 85624.7 87553.6 87868.0 89202.6 92239.5 13390.0 13498.7 13534.9 13581.2 13707.5 25373.2 25481.2 25534.2 25690.2 25860.4 8580.4 8985.7 9047.9 9082.8 9242.9 4893.0 5037.8 5054.0 5125.7 5255.0 3637.3 3714.2 3724.5 3747.3 3753.3 617.1 618.9 620.2 639.5 646.0 678.9 771.2 800.1 833.6 860.1 10419.0 10571.6 10835.3 11060.7 11297.6 785.1 801.6 818.7 831.9 839.5 5509.6 5568.1 5589.4 5632.5 5652.7 126997.0 132474.3 135204.0 135917.0 137042.0 109505.5 109945.5 111626.4 112534.0 113136.4 57383.4 57606.1 57925.0 74246.0 93483.1 10180.2 10559.0 10559.7 10698.1 10708.5 58713.7 58796.7 59424.7 59712.0 60193.4 83259.0 85891.0 87390.0 90050.5 90256.0 13635.3 13955.5 14063.9 14096.3 14106.9 15014.2 15138.3 15182.3 15217.4 15336.9 13433.1 13810.6 14052.7 14180.4 14361.6 14207.3 14467.9 14607.4 14702.4 14774.9 13633.1 13793.9 13891.1 14158.2 14354.6 11385.3 11551.0 11923.8 11956.8 12303.0 10923.5 11116.8 11335.3 11335.9 11396.4 15:25:41 INFO - PROCESS | 26663 | -------- Summary: end -------- 15:25:41 INFO - PROCESS | 26663 | 15:25:41 INFO - TEST-INFO | 26663: exit 0 15:25:41 INFO - TEST-OK | dromaeo_css | took 1110540ms 15:25:41 INFO - TEST-START | kraken 15:25:41 INFO - operating with platform_type : linux_ 15:25:41 INFO - Initialising browser for kraken test... 15:25:41 INFO - TEST-INFO | started process 26929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL1Gtni/profile http://localhost:59722/getInfo.html) 15:25:46 INFO - PROCESS | 26929 | __metrics Screen width/height:1600/1200 15:25:46 INFO - PROCESS | 26929 | colorDepth:24 15:25:46 INFO - PROCESS | 26929 | Browser inner width/height: 1024/697 15:25:46 INFO - PROCESS | 26929 | __metrics 15:25:46 ERROR - PROCESS | 26929 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 15:25:47 INFO - TEST-INFO | 26929: exit 0 15:25:47 INFO - Browser initialized. 15:25:47 INFO - Running cycle 1/1 for kraken test... 15:25:47 INFO - TEST-INFO | started process 27052 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL1Gtni/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:25:59 INFO - PROCESS | 27052 | RSS: Main: 152641536 15:25:59 INFO - PROCESS | 27052 | 15:26:01 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59722/tests/kraken/driver.html?testName=audio-beat-detection) 15:26:02 INFO - PROCESS | 27052 | RSS: Main: 157986816 15:26:02 INFO - PROCESS | 27052 | 15:26:05 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59722/tests/kraken/driver.html?testName=audio-dft) 15:26:05 INFO - PROCESS | 27052 | RSS: Main: 156782592 15:26:05 INFO - PROCESS | 27052 | 15:26:08 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59722/tests/kraken/driver.html?testName=audio-fft) 15:26:09 INFO - PROCESS | 27052 | RSS: Main: 157716480 15:26:09 INFO - PROCESS | 27052 | 15:26:11 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59722/tests/kraken/driver.html?testName=audio-oscillator) 15:26:12 INFO - PROCESS | 27052 | RSS: Main: 157077504 15:26:12 INFO - PROCESS | 27052 | 15:26:14 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59722/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:26:14 INFO - PROCESS | 27052 | RSS: Main: 156958720 15:26:14 INFO - PROCESS | 27052 | 15:26:18 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59722/tests/kraken/driver.html?testName=imaging-darkroom) 15:26:18 INFO - PROCESS | 27052 | RSS: Main: 156262400 15:26:18 INFO - PROCESS | 27052 | 15:26:22 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59722/tests/kraken/driver.html?testName=imaging-desaturate) 15:26:22 INFO - PROCESS | 27052 | RSS: Main: 155459584 15:26:22 INFO - PROCESS | 27052 | 15:26:25 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59722/tests/kraken/driver.html?testName=json-parse-financial) 15:26:26 INFO - PROCESS | 27052 | RSS: Main: 156676096 15:26:26 INFO - PROCESS | 27052 | 15:26:27 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59722/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:26:28 INFO - PROCESS | 27052 | RSS: Main: 156921856 15:26:28 INFO - PROCESS | 27052 | 15:26:30 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59722/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:26:30 INFO - PROCESS | 27052 | RSS: Main: 157110272 15:26:30 INFO - PROCESS | 27052 | 15:26:32 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59722/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:26:33 INFO - PROCESS | 27052 | RSS: Main: 157597696 15:26:33 INFO - PROCESS | 27052 | 15:26:35 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59722/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:26:36 INFO - PROCESS | 27052 | RSS: Main: 157716480 15:26:36 INFO - PROCESS | 27052 | 15:26:39 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59722/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:26:39 INFO - PROCESS | 27052 | RSS: Main: 157663232 15:26:39 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | Cycle 1(1): loaded http://localhost:59722/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59722/tests/kraken/driver.html?testName=ai-astar) 15:26:41 INFO - PROCESS | 27052 | RSS: Main: 158507008 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | __start_tp_report 15:26:41 INFO - PROCESS | 27052 | _x_x_mozilla_page_load 15:26:41 INFO - PROCESS | 27052 | _x_x_mozilla_page_load_details 15:26:41 INFO - PROCESS | 27052 | |i|pagename|runs| 15:26:41 INFO - PROCESS | 27052 | |0;ai-astar;102;113;104;111;110;111;112;111;111;114 15:26:41 INFO - PROCESS | 27052 | |1;audio-beat-detection;153;120;151;170;117;143;146;121;169;117 15:26:41 INFO - PROCESS | 27052 | |2;audio-dft;161;162;167;164;163;161;162;162;161;163 15:26:41 INFO - PROCESS | 27052 | |3;audio-fft;75;76;75;178;76;77;76;75;165;76 15:26:41 INFO - PROCESS | 27052 | |4;audio-oscillator;84;83;146;84;84;85;132;84;83;153 15:26:41 INFO - PROCESS | 27052 | |5;imaging-gaussian-blur;117;118;118;118;118;118;117;117;118;117 15:26:41 INFO - PROCESS | 27052 | |6;imaging-darkroom;147;146;147;148;147;148;146;148;147;146 15:26:41 INFO - PROCESS | 27052 | |7;imaging-desaturate;96;96;97;99;96;96;95;97;96;96 15:26:41 INFO - PROCESS | 27052 | |8;json-parse-financial;52;51;55;52;53;53;52;52;52;53 15:26:41 INFO - PROCESS | 27052 | |9;json-stringify-tinderbox;60;61;60;61;61;60;60;60;60;61 15:26:41 INFO - PROCESS | 27052 | |10;stanford-crypto-aes;79;74;75;82;73;74;73;72;73;81 15:26:41 INFO - PROCESS | 27052 | |11;stanford-crypto-ccm;118;122;117;128;130;117;122;129;170;126 15:26:41 INFO - PROCESS | 27052 | |12;stanford-crypto-pbkdf2;154;152;154;152;148;150;150;151;149;152 15:26:41 INFO - PROCESS | 27052 | |13;stanford-crypto-sha256-iterative;62;64;63;62;62;67;62;61;60;63 15:26:41 INFO - PROCESS | 27052 | __end_tp_report 15:26:41 INFO - PROCESS | 27052 | __start_cc_report 15:26:41 INFO - PROCESS | 27052 | _x_x_mozilla_cycle_collect,851 15:26:41 INFO - PROCESS | 27052 | __end_cc_report 15:26:41 INFO - PROCESS | 27052 | __startTimestamp1472768801702__endTimestamp 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | ------- Summary: start ------- 15:26:41 INFO - PROCESS | 27052 | Number of tests: 14 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:3.84 (3.5%) stddev-sans-first:2.82 15:26:41 INFO - PROCESS | 27052 | Values: 102.0 113.0 104.0 111.0 110.0 111.0 112.0 111.0 111.0 114.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:148.50 stddev:20.79 (14.0%) stddev-sans-first:21.57 15:26:41 INFO - PROCESS | 27052 | Values: 153.0 120.0 151.0 170.0 117.0 143.0 146.0 121.0 169.0 117.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#2] audio-dft Cycles:10 Average:162.60 Median:162.50 stddev:1.84 (1.1%) stddev-sans-first:1.86 15:26:41 INFO - PROCESS | 27052 | Values: 161.0 162.0 167.0 164.0 163.0 161.0 162.0 162.0 161.0 163.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#3] audio-fft Cycles:10 Average:94.90 Median:76.00 stddev:40.49 (53.3%) stddev-sans-first:42.30 15:26:41 INFO - PROCESS | 27052 | Values: 75.0 76.0 75.0 178.0 76.0 77.0 76.0 75.0 165.0 76.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#4] audio-oscillator Cycles:10 Average:101.80 Median:84.00 stddev:29.33 (34.9%) stddev-sans-first:30.40 15:26:41 INFO - PROCESS | 27052 | Values: 84.0 83.0 146.0 84.0 84.0 85.0 132.0 84.0 83.0 153.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 15:26:41 INFO - PROCESS | 27052 | Values: 117.0 118.0 118.0 118.0 118.0 118.0 117.0 117.0 118.0 117.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 15:26:41 INFO - PROCESS | 27052 | Values: 147.0 146.0 147.0 148.0 147.0 148.0 146.0 148.0 147.0 146.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:1.07 (1.1%) stddev-sans-first:1.13 15:26:41 INFO - PROCESS | 27052 | Values: 96.0 96.0 97.0 99.0 96.0 96.0 95.0 97.0 96.0 96.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.08 (2.1%) stddev-sans-first:1.13 15:26:41 INFO - PROCESS | 27052 | Values: 52.0 51.0 55.0 52.0 53.0 53.0 52.0 52.0 52.0 53.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 15:26:41 INFO - PROCESS | 27052 | Values: 60.0 61.0 60.0 61.0 61.0 60.0 60.0 60.0 60.0 61.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:74.50 stddev:3.66 (4.9%) stddev-sans-first:3.67 15:26:41 INFO - PROCESS | 27052 | Values: 79.0 74.0 75.0 82.0 73.0 74.0 73.0 72.0 73.0 81.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:127.00 stddev:15.59 (12.3%) stddev-sans-first:16.12 15:26:41 INFO - PROCESS | 27052 | Values: 118.0 122.0 117.0 128.0 130.0 117.0 122.0 129.0 170.0 126.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.20 Median:152.00 stddev:1.99 (1.3%) stddev-sans-first:1.83 15:26:41 INFO - PROCESS | 27052 | Values: 154.0 152.0 154.0 152.0 148.0 150.0 150.0 151.0 149.0 152.0 15:26:41 INFO - PROCESS | 27052 | 15:26:41 INFO - PROCESS | 27052 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.50 stddev:1.90 (3.0%) stddev-sans-first:2.00 15:26:41 INFO - PROCESS | 27052 | Values: 62.0 64.0 63.0 62.0 62.0 67.0 62.0 61.0 60.0 63.0 15:26:41 INFO - PROCESS | 27052 | -------- Summary: end -------- 15:26:41 INFO - PROCESS | 27052 | 15:26:42 INFO - TEST-INFO | 27052: exit 0 15:26:42 INFO - TEST-OK | kraken | took 60908ms 15:26:42 INFO - SUITE-END | took 1171s 15:26:42 INFO - Completed test suite (00:19:31) 15:26:42 INFO - javascript benchmark 15:26:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7749.6370174291515, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3282.031800618817, 3298.2084299768057, 3311.106001379629, 3322.1694576355912, 3329.583802024747, 5755.6258843175165, 5799.594028418007, 5817.592768506201, 5825.213596164516, 5853.385394533577, 5785.971070144644, 5856.033754430525, 5870.26621672291, 5871.412858714127, 5896.675682837442, 5289.973550132254, 5327.147656374981, 5337.039332920072, 5365.114756065264, 5392.487713667196, 5222.086134926391, 5222.399424066243, 5270.446603106657, 5305.336832895888, 5333.706646134461, 5314.973425132888, 5348.117560602496, 5351.117065684158, 5351.384590772072, 5381.623286369956, 5402.540784033342, 5406.216098665672, 5406.675599464044, 5427.3215848019, 5435.864103397446, 5056.898862022776, 5088.643794934356, 5096.719680417576, 5290.338707661543, 5291.0, 5690.06113991191, 5724.055530837407, 5730.054541000772, 5743.856403589902, 5797.681127537984, 6127.172831667757, 6169.321374648785, 6184.56708030438, 6190.0405437157315, 6195.81412557624, 5942.108683697436, 6110.847228819271, 6119.380693981191, 6184.81445556634, 6214.65819379934, 5805.970970145122, 5811.186433899257, 5837.883242335172, 5844.444777746107, 5847.152162936393, 6324.620522768649, 6346.460550853137, 6351.587146835441, 6363.96818015907, 6379.330170332141, 5873.58884878063, 6013.51891848651, 6018.127371531104, 6053.848653783691, 6091.269047714302, 6219.315875253718, 6220.129181914535, 6249.687515624291, 6250.1874756281395, 6251.74993000284, 6207.6275423474735, 6213.502919766408, 6230.6573138476915, 6232.158658581034, 6239.563230573907, 6043.637381757108, 6116.944513560587, 6127.264728232641, 6222.097795819581, 6223.937760622426, 4704.1767690654, 4715.7642117893965, 4730.929036064462, 4742.976285118552, 4772.904541909212, 4205.621494065549, 4222.239996800544, 4236.279832428495, 4238.385434112037, 4241.554636763157, 5792.304923409218, 5905.232319798399, 5917.615355001911, 5920.111983202468, 6007.309159446632, 6247.219097612799, 6262.373762623702, 6316.147320111818, 6339.904901426482, 6351.110844481776, 5999.850003749942, 6048.879022419527, 6052.697365131726, 6056.697165141725, 6060.727267272983, 5041.521055499722, 5139.871503212449, 5169.06956747789, 5233.607479439057, 5257.6056795739705, 6435.163428754232, 6471.546991710629, 6491.961286194186, 6492.513061520311, 6493.51298652603, 6285.748570057147, 6289.622622642656, 6297.590656607397, 6308.968455157878, 6309.305976342507, 6237.064440333805, 6355.205599300088, 6375.394337537927, 6456.483481321389, 6508.60948343101, 6193.907091393723, 6205.100260462298, 6205.875882482415, 6212.627242365292, 6218.471429928326, 6340.080688300263, 6348.650824204528, 6350.745970161327, 6353.14232578596, 6358.618482891041, 5898.233229680114, 6037.9735444973585, 6111.908321375272, 6208.72060757258, 6215.65813880241, 6107.97078753358, 6121.056693971993, 6125.876495711744, 6139.96930015365, 6151.477124444473, 5920.348761636131, 6032.155498230339, 6079.635221886701, 6079.908801368071, 6115.266168059861, 5973.223480947623, 6003.219581454383, 6115.3578874217565, 6140.87237156979, 6212.161358216764, 6080.939190608037, 6086.391360863879, 6088.847778805565, 6108.51131909455, 6108.72510737009], "value": 5737.917498961239, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.1354794903542, 633.5881676910008, 646.9611823290602, 681.1349586025748, 683.135833171039, 40202.396964045525, 44046.89882752929, 44142.33786493197, 44147.33778993314, 44229.0, 45244.77377613116, 45773.0, 45917.31124033142, 45945.77027114868, 45949.7702511487, 9057.818843623123, 9196.724098277062, 9232.169104780569, 9293.58178881952, 9314.72055838326, 9071.682491112811, 9241.907580924171, 9243.861342079841, 9247.167754902057, 9247.630094796234, 9230.86153707695, 9289.117533834275, 9342.532873356306, 9346.953265233664, 9388.202002829768, 9240.260779137687, 9263.583138758739, 9271.814563708756, 9320.533973301342, 9324.720258392259, 9165.0, 9187.67843125494, 9257.444553326823, 9292.442453452815, 9308.767280817967, 43861.342079868824, 43896.12207755827, 44288.3356749649, 44299.778501107285, 44366.556334436566, 3970.781417937198, 4135.069609337905, 4138.565450627701, 4197.328427451624, 4784.425868895755, 35398.646013539794, 35486.11284717877, 35696.821515892516, 36815.81592092022, 36966.260674786354, 4740.810367585293, 4756.31033500144, 4868.099401610678, 4900.436449808247, 4952.900941981176, 375080.0, 375599.0, 375685.0, 377371.1131444353, 380124.0, 38094.428583571265, 65795.34204657892, 65852.34147658462, 66107.66946165239, 66492.0, 38728.419073713914, 40535.797321013204, 40694.59305406908, 40961.0, 41340.79329603333, 17300.65398692019, 17493.212805423787, 17597.0, 17643.382481613084, 17699.20353584092, 13535.729285414236, 13615.455381784815, 13627.454901804014, 13649.0, 13659.726805464034, 9146.634134634673, 9224.123708247706, 9247.953760231156, 9291.256699464027, 9372.906270937203, 3543.94684079739, 3589.2821435712963, 3614.0, 3618.2401695644207, 3641.217138315275, 1114.6067415730338, 1145.66202970124, 1174.1839421602076, 1227.846519185102, 1263.5135472843021, 34169.65830341714, 34727.0, 34809.47785783215, 34956.0, 35468.46797298043, 1199.2431088455455, 1241.8571864235548, 1253.849538055421, 1295.1387327427155, 1296.7406518696298, 17473.7378939316, 18201.180946857425, 18212.90551586558, 18312.81687183138, 18725.719114213298, 578379.0, 581512.0, 593250.0, 595747.0, 598123.0, 41930.580694192664, 42558.36162457566, 42561.78719106447, 42631.786841065594, 42699.7865010673, 1470.1693543148087, 1498.2883130513137, 1500.9399624015136, 1506.8945173837944, 1507.2915729607066, 33151.83424082864, 33482.162945926546, 33623.83188084044, 33826.0, 34186.65813341835, 55035.44964550303, 56366.0, 56659.716701416226, 56839.147412789665, 56853.0, 382274.5317640475, 383120.0, 384078.0, 384203.0, 385817.0, 31179.37641247117, 31203.531947020358, 31239.68760312459, 31244.531332029583, 31617.20956976094, 52164.7391763054, 52843.0, 53729.0, 53835.0, 54540.0, 44878.77560612176, 45886.541134588224, 45900.77049614731, 46139.0, 47679.76160119177, 44658.0, 45811.3128303069, 46225.0, 46581.3012804815, 47820.521794781605, 39975.400368993905, 43682.126357472036, 45604.315935261664, 45942.770286148356, 46210.306845398016, 38702.35685186484, 39460.40809387919, 40090.0, 40142.397864032646, 40861.795691021354, 31023.379532409675, 31139.68860311368, 31329.0, 31451.213719657193, 31707.841460792548], "value": 20244.752028153638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.13040484577556, 416.1199063979684, 422.91753108143917, 434.53938824845665, 441.72171531934896, 19064.23743050278, 19988.60022799545, 20543.48641283967, 21150.0, 21172.25897093602, 20649.070791814367, 20779.064942077606, 20916.26793062243, 21559.1376344946, 21752.0, 5948.583599148062, 6175.197224360849, 6179.5674302798825, 6182.258129024501, 6182.814515564541, 5620.381758006616, 5843.590948633598, 5898.675572843492, 6028.367021462749, 6083.17877086592, 5825.126231065332, 5869.618474799146, 5913.379095195007, 5929.466348028676, 5971.0, 5774.133879918025, 5894.941050589482, 5912.231409916726, 5961.791337303195, 5963.045912653998, 5864.413558644144, 6060.454559089659, 6069.514438844882, 6072.908906366408, 6080.878382432326, 16129.596760080974, 24360.147394841097, 24769.00923963039, 24769.380765481008, 24822.751772482225, 2428.4293191100255, 2437.7074751029813, 2488.216211893248, 2491.869451618876, 2994.3412449261127, 10257.794844103077, 10672.573097076107, 10734.677959661223, 10739.140868730483, 10789.730256743565, 1988.6519859024756, 2054.732884724991, 2080.2615071649498, 2083.1771450277206, 2084.093419362582, 82850.0, 83914.0, 86924.56537717271, 87372.56313718454, 89437.10562894352, 11525.423728813694, 11854.407279635983, 11855.46650400734, 11872.643820685393, 11889.940550297193, 16820.41128560519, 16841.157942102847, 16841.747373789403, 16842.15789210559, 16967.49097527076, 8540.871886921701, 8573.957130214434, 8577.95711021441, 8751.868721969175, 9115.954420227856, 8852.911470885338, 8879.067697891758, 9041.77395565103, 9119.544022798833, 9223.216026637676, 4873.269009648581, 4931.358923340014, 5116.7624705076805, 5145.639805213599, 5265.315508983807, 1948.5420926082359, 1958.745363102816, 2053.414776788608, 2177.227084385052, 2197.2748992832326, 1014.2301683562231, 1044.6291566493785, 1055.8205105132147, 1097.6926460591046, 1139.4701463819363, 20280.08739606721, 20921.581568368547, 21258.36224913255, 21440.0, 21917.452063698536, 1073.608133031438, 1090.2967585907045, 1105.077260487502, 1135.239389608964, 1148.471703016605, 11858.407079645984, 12149.331786751813, 12166.513339466499, 12184.329861857683, 12336.198147120409, 542252.0, 573805.0, 578282.0, 581721.0, 586332.0, 88009.0, 89726.55136724275, 96889.0, 99558.50220748995, 100777.0, 1066.973325666849, 1075.6014088582506, 1102.614085070219, 1119.0488085127577, 1120.5293776613842, 18513.44459666212, 19077.523061923563, 19372.0, 19417.708734368713, 19631.214751409785, 43886.34170487509, 44284.33573496336, 44319.11361772811, 44593.55406445894, 45351.546484536055, 58149.0, 61333.69333153484, 64061.35938640741, 64432.67783661051, 65666.34333656602, 8472.83054338922, 9197.86203206939, 9198.0, 9200.26397888154, 9445.905540944503, 11823.940880295544, 14038.78941815853, 14150.78773818373, 14170.433182672577, 14716.0, 12295.815562766387, 12488.562900298446, 12629.3685315732, 12670.87329126697, 12697.746045079231, 11742.765144696887, 12181.269123852597, 12232.143749937775, 12413.193142445713, 12922.353882306035, 11904.761904761683, 12267.263964161823, 12566.434510446868, 12570.245785252913, 12739.490420383081, 8983.550822458721, 9263.44419334815, 9271.814563708553, 9386.108319709616, 9429.85839782982, 10702.357858528514, 11786.705332366759, 12356.320402377956, 12643.936780316408, 12644.114911956074], "value": 9887.936537291951, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1555.64997875478, 1578.2739939627775, 1755.2891079112965, 1819.490542648059, 1860.0699650174913, 8364.665413383473, 8934.955325223365, 9032.638694452222, 9791.314607977445, 9803.411795292279, 9192.724218273446, 9501.144896959273, 9509.191718703902, 9574.04259574044, 9617.422954622727, 2456.263121063678, 2482.8758562071826, 2483.900643974248, 2489.0666000249907, 2516.0816302049802, 2442.1086303499274, 2496.900123995047, 2499.0, 2500.237427584586, 2502.637117617944, 2449.9265022049367, 2456.9312349128186, 2484.527939691462, 2501.062101711858, 2512.811539134563, 2427.6965379327585, 2429.3926518370495, 2434.050720219116, 2454.840435371695, 2470.0737223541173, 2419.63705444183, 2449.9755002449924, 2456.766607172334, 2463.8152138589676, 2464.728879823218, 9582.616695332179, 9647.855282170704, 9652.22782177424, 9655.937846836843, 9663.806723865484, 5499.175123731432, 5590.077637189859, 5604.915926261109, 5606.21512988182, 5606.607537472379, 3531.399662057456, 3622.6920711739535, 3664.395374763161, 3683.0239986403626, 3688.8708895188806, 1151.5681619392728, 1172.132621859826, 1177.6290468502393, 1191.6544202181358, 1195.4680167325544, 12403.317817520032, 12428.62714118578, 12478.064145189148, 12519.998400128059, 12535.811962820566, 4721.655494025053, 4728.3143944127905, 4802.951970480251, 4894.339264199359, 4896.5837903777865, 10604.89395106039, 10792.51433685486, 10802.189835762349, 10857.022867941923, 10899.346039237671, 5568.192612071227, 5581.6371935824045, 5595.992721310122, 5603.971980140073, 5608.46719561641, 4612.654050946193, 4983.875403114951, 5144.279800827887, 5196.428392876781, 5213.7914483421, 4330.090680957034, 4408.977955110268, 4424.11517696462, 4473.351364052194, 4485.587040082363, 1389.270632917706, 1410.4358256697199, 1424.8503907089814, 1429.1567974894863, 1438.5900018494679, 968.375397868371, 1028.7273872423814, 1115.3642423818508, 1117.4412793603199, 1127.9605213817476, 6606.306337834554, 6728.730850766013, 6757.0, 6846.931530684629, 6849.691763870638, 469.4062011555385, 487.8316980641673, 490.443346801383, 493.73338397265076, 495.04950495049593, 4728.95271047292, 4834.154023045953, 4946.480619534969, 4950.405951285797, 4966.0, 11365.204435689588, 11812.645620631394, 11930.0, 11968.162228644085, 11975.940120299518, 11387.430628468543, 11608.477618507188, 11736.706582335339, 11801.822972655416, 11866.940665296619, 8543.572821358906, 8671.21959023691, 8741.907261592301, 8744.7376578704, 8757.47455152693, 6772.729090836311, 6848.7260509579055, 6878.518503704692, 6902.792916212522, 6926.376626103674, 10386.11718003978, 10526.473676316, 10674.35953842772, 10753.677389678322, 10856.728581785519, 10757.892421076001, 10879.782404352027, 10886.836697449702, 11165.497552610315, 11207.887921120684, 7356.264373562601, 8104.068032176139, 8564.05795362522, 8598.441101328393, 8724.65101395937, 9378.812423751351, 9417.576209070745, 9452.480113593818, 9472.194863436409, 9548.045195480396, 8323.875141872755, 8507.574621269036, 8510.574471276535, 8555.486670799772, 8559.657613695383, 8287.378446616649, 8354.331653467829, 8364.749057528285, 8454.830903381775, 8457.112003239818, 7882.802929926568, 7909.960450197712, 7921.564313962787, 7933.690940994671, 8004.519728816057, 5847.210626565359, 5900.793472228452, 5990.880182396415, 6016.789412370546, 6101.054336577838, 5870.26621672291, 5942.524598032233, 5977.193078934288, 5980.730867110903, 6001.639901605918], "value": 4940.087894816512, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.5916588842222, 348.6931500279753, 350.24696901661434, 356.7057177828289, 357.456665867881, 5803.941960580393, 5808.56435767317, 5932.932072226998, 5963.731632076556, 5971.820845374646, 5764.51001664859, 5792.82621521355, 5793.159991801186, 5819.243498345219, 5846.649201047941, 4600.907981840378, 4632.911265852514, 4671.182543054969, 4673.789679464432, 4681.204195286809, 4453.153900758862, 4585.403897493338, 4610.907781844361, 4621.0, 4680.391549098629, 4557.156925968707, 4572.862814115582, 4580.931286030712, 4593.54245478669, 4624.260118381059, 4338.262495375794, 4594.264917613183, 4635.976820115911, 4647.512011238805, 4649.674522783406, 3939.9015024624327, 3949.2299001694682, 3971.6822654187886, 3974.503187101612, 3975.304321743712, 5658.0, 5695.629784064023, 5720.3135623725, 5751.942480575182, 5772.422757724236, 5139.794408223666, 5194.6104042196985, 5239.659422137549, 5260.631755777097, 5263.6578622389425, 5995.880082398371, 6071.605345652562, 6074.906516826969, 6100.084987251903, 6106.236720409949, 4391.516933137353, 4405.273129933557, 4431.933520997188, 4446.866594002185, 4460.375547423363, 6188.814335569895, 6310.905336419957, 6369.426751592333, 6391.137196478462, 6398.808035758934, 4457.576530229623, 4459.509453960061, 4464.151811155935, 4482.327650852333, 4515.932261016087, 4564.269716845289, 4568.17772800899, 4571.9314210286875, 4578.725276483422, 4585.839495617637, 4980.551750342486, 5199.844004679866, 5211.8175863845345, 5230.92153617696, 5233.973830130901, 5948.691287916651, 6065.211522502046, 6080.908786368208, 6109.175261339662, 6109.389061093855, 1552.2937063636018, 1567.2242240091227, 1572.1353255709314, 1578.2187817030642, 1582.8575428211286, 969.4038166527675, 989.1592146675268, 1005.1707341443339, 1015.7105225010837, 1017.1354348803605, 5253.658512196695, 5255.0, 5284.912459578445, 5290.444503327172, 5324.41431442541, 5524.530414914777, 5572.969000734877, 5579.2746942897165, 5607.831765047055, 5611.334616057422, 5036.521530454601, 5266.708307307933, 5318.468153184728, 5340.519353258225, 5356.276902618175, 2949.41011797637, 2989.50673138934, 3024.3668419053906, 3030.2424393901524, 3035.408095421416, 5720.541261978169, 5740.0815869460685, 5761.135829625589, 5777.382332946781, 5785.50823180026, 6036.698165091728, 6369.20384951881, 6477.77327793525, 6478.481721462272, 6495.805125846232, 6512.120863683342, 6606.735730570724, 6625.701843417058, 6636.113290718553, 6666.500012499082, 6072.0, 6201.627902325875, 6303.684815759102, 6323.336049714714, 6342.682865856596, 6291.483406637381, 6304.558680892477, 6313.747450101944, 6373.107764912823, 6383.585066970632, 6323.272823625158, 6403.29563747978, 6565.310642382481, 6593.208814942238, 6679.0, 4459.910801783882, 4496.7301961882395, 4518.548145185455, 4544.931826022546, 4568.725876447424, 6156.0, 6258.780942666985, 6452.096706461005, 6454.99947508137, 6472.223333200046, 6174.691265436621, 6192.783252586138, 6208.255009398901, 6214.104698067133, 6225.875482490234, 6034.2155519782145, 6161.39803651045, 6167.5682702210415, 6175.197224360984, 6219.782307619212, 6117.510599152146, 6132.63204207749, 6141.785037523665, 6146.385361463817, 6146.739918316645, 6207.317195108625, 6241.531885108726, 6241.656708881055, 6253.937460625336, 6319.9052014218905, 5785.623934444247, 5999.4900433463645, 6022.524481501885, 6044.184035155374, 6059.878802424015], "value": 4613.385934671268, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [819.7499762572413, 819.893413856199, 824.2787560884228, 865.4417900454205, 929.0, 15362.539123826275, 16210.918945405272, 17691.11544422777, 18193.27226910922, 18327.450176494727, 16463.094529800856, 16523.83476165238, 16635.50093497197, 16832.579185520368, 16895.662086758257, 752.7026824404358, 890.5146695051212, 894.8389289927827, 909.5725009245645, 913.5751875377948, 893.0266010049045, 902.5848109869468, 903.7198468474794, 904.1997831918767, 904.2449554621866, 967.0281367225928, 970.1317320997703, 971.3977334052897, 973.274910191908, 974.8878878928907, 949.4255975135047, 951.3673407184283, 955.9904400955971, 961.5336561767538, 962.5235508423306, 703.5813690853934, 703.7325816189866, 709.0427922304899, 709.4927127104145, 709.8225443639091, 15873.047617142893, 16126.838731612652, 16175.35298588055, 16203.594910127224, 16395.672086558323, 15971.041737495787, 16493.257803398756, 16809.495715128567, 16987.3205071797, 17108.914455427766, 13581.0, 13612.251326177056, 13623.863761362358, 13647.590572282847, 14044.297785110704, 4090.734102283342, 4137.710360274782, 4154.5222299435645, 4158.106007208435, 4164.646005089541, 85624.71562926561, 87553.56223218843, 87868.0, 89202.55398723029, 92239.53880230556, 13390.0, 13498.730025399633, 13534.86465135336, 13581.185128892299, 13707.451701931812, 25373.238802835946, 25481.235562932772, 25534.23397298084, 25690.229293121236, 25860.35349116287, 8580.35647326453, 8985.730428087169, 9047.864282035775, 9082.772930676785, 9242.86135707965, 4893.0, 5037.79848806051, 5053.974730126326, 5125.692458452505, 5255.02781985334, 3637.2725454909123, 3714.220013797079, 3724.5344331958504, 3747.34421476246, 3753.305638456894, 617.0528239152878, 618.8576627375638, 620.2247191011236, 639.4820195641545, 645.964471954047, 678.9321067893171, 771.248033167657, 800.0519384529351, 833.5748768128177, 860.0840105287848, 10418.961974808628, 10571.629992950364, 10835.295705779099, 11060.668179954615, 11297.604583839526, 785.1363500149904, 801.5951944268223, 818.6602559937678, 831.9251267385964, 839.5004972041626, 5509.559235261169, 5568.081266591048, 5589.441055894378, 5632.464915832989, 5652.689102099342, 126997.0, 132474.33762831317, 135204.0, 135917.0, 137042.0, 109505.45247273713, 109945.45027274813, 111626.44186779014, 112534.0, 113136.43431782788, 57383.426165738645, 57606.13590796141, 57925.0, 74246.0, 93483.0651693488, 10180.185585153318, 10559.0, 10559.736006599896, 10698.090662293482, 10708.518116684923, 58713.706431467566, 58796.706016469645, 59424.70287648534, 59712.0, 60193.39806601878, 83259.0, 85891.0, 87390.0, 90050.54974725084, 90256.0, 13635.250061246727, 13955.511557095453, 14063.859361406256, 14096.295185240902, 14106.929465352607, 15014.249287535798, 15138.318775654901, 15182.316795743996, 15217.391304348144, 15336.923315383352, 13433.126846754929, 13810.585682429542, 14052.718945620825, 14180.432782688576, 14361.64095897611, 14207.28963551839, 14467.92766036163, 14607.415703371746, 14702.411903524167, 14774.852251477347, 13633.113847599874, 13793.86206137966, 13891.097078689852, 14158.150510969375, 14354.641133971734, 11385.259958102697, 11551.0, 11923.82114268304, 11956.820647690465, 12303.0, 10923.508442120283, 11116.832732562965, 11335.263207891461, 11335.886641133813, 11396.430178490877], "value": 8274.122097745018, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 104.0, 111.0, 110.0, 111.0, 112.0, 111.0, 111.0, 114.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 151.0, 170.0, 117.0, 143.0, 146.0, 121.0, 169.0, 117.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 167.0, 164.0, 163.0, 161.0, 162.0, 162.0, 161.0, 163.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 178.0, 76.0, 77.0, 76.0, 75.0, 165.0, 76.0], "value": 94.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 146.0, 84.0, 84.0, 85.0, 132.0, 84.0, 83.0, 153.0], "value": 101.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 118.0, 118.0, 118.0, 117.0, 117.0, 118.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 148.0, 147.0, 148.0, 146.0, 148.0, 147.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 99.0, 96.0, 96.0, 95.0, 97.0, 96.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 55.0, 52.0, 53.0, 53.0, 52.0, 52.0, 52.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 75.0, 82.0, 73.0, 74.0, 73.0, 72.0, 73.0, 81.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 122.0, 117.0, 128.0, 130.0, 117.0, 122.0, 129.0, 170.0, 126.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 152.0, 154.0, 152.0, 148.0, 150.0, 150.0, 151.0, 149.0, 152.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 62.0, 62.0, 67.0, 62.0, 61.0, 60.0, 63.0], "value": 62.6, "unit": "score"}]}]} 15:26:42 INFO - Return code: 0 15:26:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:26:43 ERROR - # TBPL SUCCESS # 15:26:43 INFO - Running post-action listener: _resource_record_post_action 15:26:43 INFO - [mozharness: 2016-09-01 22:26:43.009126Z] Finished run-tests step (success) 15:26:43 INFO - Running post-run listener: _resource_record_post_run 15:26:43 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328425472; Read time: 8; Write time: 245500 15:26:43 INFO - TinderboxPrint: CPU usage
13.3% 15:26:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 15:26:43 INFO - TinderboxPrint: I/O write bytes / time
328,425,472 / 245,500 15:26:43 INFO - TinderboxPrint: CPU idle
8,221.2 (86.7%) 15:26:43 INFO - TinderboxPrint: CPU user
1,200.6 (12.7%) 15:26:43 INFO - TinderboxPrint: Swap in / out
0 / 0 15:26:43 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 77197312; Read time: 0; Write time: 67864 15:26:43 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 248741888; Read time: 0; Write time: 177428 15:26:43 INFO - Running post-run listener: _upload_blobber_files 15:26:43 INFO - Blob upload gear active. 15:26:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:26:43 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 15:26:43 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'] 15:26:43 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 15:26:44 INFO - (blobuploader) - INFO - Open directory for files ... 15:26:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:26:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:26:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:44 INFO - (blobuploader) - INFO - Done attempting. 15:26:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:26:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:26:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:45 INFO - (blobuploader) - INFO - Done attempting. 15:26:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:26:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:26:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:26:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:26:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:26:50 INFO - (blobuploader) - INFO - Done attempting. 15:26:50 INFO - (blobuploader) - INFO - Iteration through files over. 15:26:50 INFO - Return code: 0 15:26:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:26:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:26:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9513d16cb48aa7409eeec7ea92e093bc9aec788c197e4dfc1e66a21c1eb46fabcfd4f4f49f31f358304a6803c6d029a0ce604ffc2054aaa659e0dc63ae536a22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9117338630c4cc730a47d9068d84067a0e2b348d880610fb941523030c6b1b604f2d95c43cd68dd0c589a5d5bf6bde3f81d81ff6aca3d66e925f7222c80e7350", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ef400db2cac79e8e6c7374e3ad4b48a24a570a64773940912814cdb9cbca43f6828ff6a2c96a512d73630e479628feda2385c08786a45d97224551b7e8596e1"} 15:26:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:26:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:26:50 INFO - Contents: 15:26:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9513d16cb48aa7409eeec7ea92e093bc9aec788c197e4dfc1e66a21c1eb46fabcfd4f4f49f31f358304a6803c6d029a0ce604ffc2054aaa659e0dc63ae536a22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9117338630c4cc730a47d9068d84067a0e2b348d880610fb941523030c6b1b604f2d95c43cd68dd0c589a5d5bf6bde3f81d81ff6aca3d66e925f7222c80e7350", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ef400db2cac79e8e6c7374e3ad4b48a24a570a64773940912814cdb9cbca43f6828ff6a2c96a512d73630e479628feda2385c08786a45d97224551b7e8596e1"} 15:26:50 INFO - Running post-run listener: copy_logs_to_upload_dir 15:26:50 INFO - Copying logs to upload dir... 15:26:50 INFO - mkdir: /builds/slave/test/build/upload/logs 15:26:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.163205 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-01 15:26:49.421683) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 15:26:49.425355) ========= 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=523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9513d16cb48aa7409eeec7ea92e093bc9aec788c197e4dfc1e66a21c1eb46fabcfd4f4f49f31f358304a6803c6d029a0ce604ffc2054aaa659e0dc63ae536a22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9117338630c4cc730a47d9068d84067a0e2b348d880610fb941523030c6b1b604f2d95c43cd68dd0c589a5d5bf6bde3f81d81ff6aca3d66e925f7222c80e7350", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ef400db2cac79e8e6c7374e3ad4b48a24a570a64773940912814cdb9cbca43f6828ff6a2c96a512d73630e479628feda2385c08786a45d97224551b7e8596e1"} build_url:https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011337 build_url: 'https://queue.taskcluster.net/v1/task/LIsIt-xQS0Kd0YF3B7TJAQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9513d16cb48aa7409eeec7ea92e093bc9aec788c197e4dfc1e66a21c1eb46fabcfd4f4f49f31f358304a6803c6d029a0ce604ffc2054aaa659e0dc63ae536a22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9117338630c4cc730a47d9068d84067a0e2b348d880610fb941523030c6b1b604f2d95c43cd68dd0c589a5d5bf6bde3f81d81ff6aca3d66e925f7222c80e7350", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ef400db2cac79e8e6c7374e3ad4b48a24a570a64773940912814cdb9cbca43f6828ff6a2c96a512d73630e479628feda2385c08786a45d97224551b7e8596e1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 15:26:49.452874) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 15:26:49.453183) ========= 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=523ca41ca1e0623de333ad52000001bf-1472766883.316974-25129412 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 15:26:49.535868) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 15:26:49.536192) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 15:26:49.536483) ========= ========= Total master_lag: 0.16 =========