builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1473762934.25 results: success (0) buildid: 20160913004005 builduid: cb2bff480acd4cf5834ac85ac015eb5b revision: 696a981b6d534a7015d6f2ba3d2379af620e9b26 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:34.246260) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:34.247008) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:34.247344) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:34.274522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:34.274835) ========= 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=652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-13 03:35:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-09-13 03:35:34 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119032 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:34.408754) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:34.409103) ========= 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=652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019824 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:34.458885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:34.459250) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 696a981b6d534a7015d6f2ba3d2379af620e9b26 --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 696a981b6d534a7015d6f2ba3d2379af620e9b26 --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=652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-13 03:35:34,757 truncating revision to first 12 chars 2016-09-13 03:35:34,757 Setting DEBUG logging. 2016-09-13 03:35:34,757 attempt 1/10 2016-09-13 03:35:34,757 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/696a981b6d53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-13 03:35:35,221 unpacking tar archive at: mozilla-aurora-696a981b6d53/testing/mozharness/ program finished with exit code 0 elapsedTime=0.799991 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:35.273505) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:35.273858) ========= script_repo_revision: 696a981b6d534a7015d6f2ba3d2379af620e9b26 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:35.274335) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:35.274644) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:35.284326) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 44 secs) (at 2016-09-13 03:35:35.284650) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:35:35 INFO - MultiFileLogger online at 20160913 03:35:35 in /builds/slave/test 03:35:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:35:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:35:35 INFO - {'append_to_log': False, 03:35:35 INFO - 'base_work_dir': '/builds/slave/test', 03:35:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:35:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:35:35 INFO - 'branch': 'Mozilla-Aurora', 03:35:35 INFO - 'buildbot_json_path': 'buildprops.json', 03:35:35 INFO - 'config_files': ('talos/linux_config.py',), 03:35:35 INFO - 'default_actions': ('clobber', 03:35:35 INFO - 'read-buildbot-config', 03:35:35 INFO - 'download-and-extract', 03:35:35 INFO - 'populate-webroot', 03:35:35 INFO - 'create-virtualenv', 03:35:35 INFO - 'install', 03:35:35 INFO - 'run-tests'), 03:35:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:35:35 INFO - 'download_minidump_stackwalk': True, 03:35:35 INFO - 'download_symbols': 'ondemand', 03:35:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:35:35 INFO - 'tooltool.py': '/tools/tooltool.py', 03:35:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:35:35 INFO - '/tools/misc-python/virtualenv.py')}, 03:35:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:35:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:35:35 INFO - 'installer_path': 'installer.exe', 03:35:35 INFO - 'log_level': 'info', 03:35:35 INFO - 'log_name': 'talos', 03:35:35 INFO - 'log_to_console': True, 03:35:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:35:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:35:35 INFO - 'opt_config_files': (), 03:35:35 INFO - 'pip_index': False, 03:35:35 INFO - 'sps_profile': False, 03:35:35 INFO - 'sps_profile_interval': 0, 03:35:35 INFO - 'suite': 'dromaeojs', 03:35:35 INFO - 'system_bits': '32', 03:35:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:35:35 INFO - 'title': 'talos-linux64-ix-046', 03:35:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:35:35 INFO - 'use_talos_json': True, 03:35:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:35:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:35:35 INFO - 'work_dir': 'build'} 03:35:35 INFO - [mozharness: 2016-09-13 10:35:35.681953Z] Running clobber step. 03:35:35 INFO - Running pre-action listener: _resource_record_pre_action 03:35:35 INFO - Running main action method: clobber 03:35:35 INFO - rmtree: /builds/slave/test/build 03:35:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:35:36 INFO - Running post-action listener: _resource_record_post_action 03:35:36 INFO - [mozharness: 2016-09-13 10:35:36.133629Z] Finished clobber step (success) 03:35:36 INFO - [mozharness: 2016-09-13 10:35:36.133729Z] Running read-buildbot-config step. 03:35:36 INFO - Running pre-action listener: _resource_record_pre_action 03:35:36 INFO - Running main action method: read_buildbot_config 03:35:36 INFO - Using buildbot properties: 03:35:36 INFO - { 03:35:36 INFO - "project": "", 03:35:36 INFO - "product": "firefox", 03:35:36 INFO - "script_repo_revision": "production", 03:35:36 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:35:36 INFO - "repository": "", 03:35:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:35:36 INFO - "buildid": "20160913004005", 03:35:36 INFO - "stage_platform": "linux64-pgo", 03:35:36 INFO - "basedir": "/builds/slave/test", 03:35:36 INFO - "nightly_build": "True", 03:35:36 INFO - "buildnumber": 469, 03:35:36 INFO - "slavename": "talos-linux64-ix-046", 03:35:36 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:35:36 INFO - "platform": "ubuntu64_hw", 03:35:36 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:35:36 INFO - "branch": "mozilla-aurora", 03:35:36 INFO - "repo_path": "releases/mozilla-aurora", 03:35:36 INFO - "revision": "696a981b6d534a7015d6f2ba3d2379af620e9b26", 03:35:36 INFO - "pgo_build": "True", 03:35:36 INFO - "builduid": "cb2bff480acd4cf5834ac85ac015eb5b", 03:35:36 INFO - "slavebuilddir": "test" 03:35:36 INFO - } 03:35:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:35:36 INFO - Running post-action listener: _resource_record_post_action 03:35:36 INFO - [mozharness: 2016-09-13 10:35:36.136240Z] Finished read-buildbot-config step (success) 03:35:36 INFO - [mozharness: 2016-09-13 10:35:36.136329Z] Running download-and-extract step. 03:35:36 INFO - Running pre-action listener: _resource_record_pre_action 03:35:36 INFO - Running main action method: download_and_extract 03:35:36 INFO - mkdir: /builds/slave/test/build/tests 03:35:36 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')]} 03:35:36 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:35:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:35:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:35:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:35:36 INFO - retry: Failed, sleeping 30 seconds before retrying 03:36:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:36:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:36:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:36:06 INFO - retry: Failed, sleeping 60 seconds before retrying 03:37:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:37:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:37:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:37:06 INFO - Caught exception: HTTP Error 404: Not Found 03:37:06 INFO - Caught exception: HTTP Error 404: Not Found 03:37:06 INFO - Caught exception: HTTP Error 404: Not Found 03:37:06 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:06 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:37:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:37:06 INFO - Downloaded 1361 bytes. 03:37:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:06 INFO - Using the following test package requirements: 03:37:06 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:37:06 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:37:06 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:37:06 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:06 INFO - u'jsshell-linux-x86_64.zip'], 03:37:06 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:37:06 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:37:06 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:37:06 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:37:06 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:37:06 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:37:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:37:06 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:06 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:37:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:37:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:37:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:37:06 INFO - retry: Failed, sleeping 30 seconds before retrying 03:37:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:37:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:37:36 INFO - retry: Failed, sleeping 60 seconds before retrying 03:38:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:38:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:38:36 INFO - Caught exception: HTTP Error 404: Not Found 03:38:36 INFO - Caught exception: HTTP Error 404: Not Found 03:38:36 INFO - Caught exception: HTTP Error 404: Not Found 03:38:36 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:36 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:38 INFO - Downloaded 21770035 bytes. 03:38:38 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'] 03:38:38 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 03:38:39 INFO - Return code: 0 03:38:39 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 03:38:39 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')]} 03:38:39 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:38:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:38:39 INFO - retry: Failed, sleeping 30 seconds before retrying 03:39:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:39:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:39:09 INFO - retry: Failed, sleeping 60 seconds before retrying 03:40:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:40:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:40:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:40:09 INFO - Caught exception: HTTP Error 404: Not Found 03:40:09 INFO - Caught exception: HTTP Error 404: Not Found 03:40:09 INFO - Caught exception: HTTP Error 404: Not Found 03:40:09 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:09 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:40:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:40:10 INFO - Downloaded 21770035 bytes. 03:40:10 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'] 03:40:10 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 03:40:11 INFO - Return code: 0 03:40:11 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')]} 03:40:11 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:40:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:40:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:40:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:40:11 INFO - retry: Failed, sleeping 30 seconds before retrying 03:40:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:40:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:40:41 INFO - retry: Failed, sleeping 60 seconds before retrying 03:41:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:41:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:41:41 INFO - Caught exception: HTTP Error 404: Not Found 03:41:41 INFO - Caught exception: HTTP Error 404: Not Found 03:41:41 INFO - Caught exception: HTTP Error 404: Not Found 03:41:41 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:41 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:42 INFO - Downloaded 14413740 bytes. 03:41:42 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'] 03:41:42 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 03:41:42 INFO - Return code: 0 03:41:42 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')]} 03:41:42 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:41:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:41:42 INFO - retry: Failed, sleeping 30 seconds before retrying 03:42:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:42:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:42:12 INFO - retry: Failed, sleeping 60 seconds before retrying 03:43:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:43:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:43:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:43:12 INFO - Caught exception: HTTP Error 404: Not Found 03:43:12 INFO - Caught exception: HTTP Error 404: Not Found 03:43:12 INFO - Caught exception: HTTP Error 404: Not Found 03:43:12 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:12 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:43:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:43:14 INFO - Downloaded 63870898 bytes. 03:43:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:14 INFO - mkdir: /builds/slave/test/properties 03:43:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:43:14 INFO - Writing to file /builds/slave/test/properties/build_url 03:43:14 INFO - Contents: 03:43:14 INFO - build_url:https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:14 INFO - Running post-action listener: _resource_record_post_action 03:43:14 INFO - Running post-action listener: set_extra_try_arguments 03:43:14 INFO - [mozharness: 2016-09-13 10:43:14.726240Z] Finished download-and-extract step (success) 03:43:14 INFO - [mozharness: 2016-09-13 10:43:14.726471Z] Running populate-webroot step. 03:43:14 INFO - Running pre-action listener: _resource_record_pre_action 03:43:14 INFO - Running main action method: populate_webroot 03:43:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:43:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:43:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:43:14 INFO - '--tppagecycles', 03:43:14 INFO - '7'], 03:43:14 INFO - 'tests': ['tsvgm']}}, 03:43:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:43:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:43:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:43:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:43:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:14 INFO - 'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:14 INFO - 'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['damp', 'tps']}, 03:43:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:14 INFO - 'tests': ['damp', 'tps']}, 03:43:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['dromaeo_dom']}, 03:43:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:43:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['basic_compositor_video']}, 03:43:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:43:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['a11yr', 03:43:14 INFO - 'ts_paint', 03:43:14 INFO - 'tpaint', 03:43:14 INFO - 'sessionrestore', 03:43:14 INFO - 'sessionrestore_no_auto_restore', 03:43:14 INFO - 'tabpaint']}, 03:43:14 INFO - 'other-e10s': {'tests': ['a11yr', 03:43:14 INFO - 'ts_paint', 03:43:14 INFO - 'tpaint', 03:43:14 INFO - 'sessionrestore', 03:43:14 INFO - 'sessionrestore_no_auto_restore', 03:43:14 INFO - 'tabpaint']}, 03:43:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:43:14 INFO - 'ts_paint', 03:43:14 INFO - 'tpaint', 03:43:14 INFO - 'sessionrestore', 03:43:14 INFO - 'sessionrestore_no_auto_restore', 03:43:14 INFO - 'tabpaint']}, 03:43:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:43:14 INFO - 'ts_paint', 03:43:14 INFO - 'tpaint', 03:43:14 INFO - 'sessionrestore', 03:43:14 INFO - 'sessionrestore_no_auto_restore', 03:43:14 INFO - 'tabpaint']}, 03:43:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['a11yr', 03:43:14 INFO - 'ts_paint', 03:43:14 INFO - 'tpaint', 03:43:14 INFO - 'sessionrestore', 03:43:14 INFO - 'sessionrestore_no_auto_restore', 03:43:14 INFO - 'tabpaint']}, 03:43:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['a11yr', 03:43:14 INFO - 'ts_paint', 03:43:14 INFO - 'tpaint', 03:43:14 INFO - 'sessionrestore', 03:43:14 INFO - 'sessionrestore_no_auto_restore', 03:43:14 INFO - 'tabpaint']}, 03:43:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['tsvgx', 03:43:14 INFO - 'tsvgr_opacity', 03:43:14 INFO - 'tart', 03:43:14 INFO - 'tscrollx', 03:43:14 INFO - 'cart']}, 03:43:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:43:14 INFO - 'tsvgr_opacity', 03:43:14 INFO - 'tart', 03:43:14 INFO - 'tscrollx', 03:43:14 INFO - 'cart']}, 03:43:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:14 INFO - 'talos_options': ['--disable-e10s'], 03:43:14 INFO - 'tests': ['tp5o']}, 03:43:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:14 INFO - 'tests': ['tp5o']}, 03:43:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:14 INFO - 'talos_options': ['--disable-e10s', 03:43:14 INFO - '--xperf_path', 03:43:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:14 INFO - 'tests': ['tp5n']}, 03:43:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:43:14 INFO - 'talos_options': ['--xperf_path', 03:43:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:14 INFO - 'tests': ['tp5n']}}, 03:43:14 INFO - 'talos.zip': {'path': '', 03:43:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:43:14 INFO - Running post-action listener: _resource_record_post_action 03:43:14 INFO - [mozharness: 2016-09-13 10:43:14.741788Z] Finished populate-webroot step (success) 03:43:14 INFO - [mozharness: 2016-09-13 10:43:14.741878Z] Running create-virtualenv step. 03:43:14 INFO - Running pre-action listener: _resource_record_pre_action 03:43:14 INFO - Running main action method: create_virtualenv 03:43:14 INFO - Creating virtualenv /builds/slave/test/build/venv 03:43:14 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 03:43:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:43:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:43:14 INFO - Using real prefix '/usr' 03:43:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:43:15 INFO - Installing distribute.............................................................................................................................................................................................done. 03:43:17 INFO - Installing pip.................done. 03:43:17 INFO - Return code: 0 03:43:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:17 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:17 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 03:43:17 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 03:43:17 INFO - Using env: {'DISPLAY': ':0', 03:43:17 INFO - 'HOME': '/home/cltbld', 03:43:17 INFO - 'LANG': 'en_US.UTF-8', 03:43:17 INFO - 'LANGUAGE': 'en_US:en', 03:43:17 INFO - 'LOGNAME': 'cltbld', 03:43:17 INFO - 'MAIL': '/var/mail/cltbld', 03:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:17 INFO - 'MOZ_NO_REMOTE': '1', 03:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:17 INFO - 'NO_EM_RESTART': '1', 03:43:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:17 INFO - 'PWD': '/builds/slave/test', 03:43:17 INFO - 'SHELL': '/bin/bash', 03:43:17 INFO - 'SHLVL': '1', 03:43:17 INFO - 'TERM': 'linux', 03:43:17 INFO - 'TMOUT': '86400', 03:43:17 INFO - 'USER': 'cltbld', 03:43:17 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:17 INFO - '_': '/tools/buildbot/bin/python'} 03:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:17 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 03:43:17 INFO - Cleaning up... 03:43:17 INFO - Return code: 0 03:43:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:17 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:17 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 03:43:17 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 03:43:17 INFO - Using env: {'DISPLAY': ':0', 03:43:17 INFO - 'HOME': '/home/cltbld', 03:43:17 INFO - 'LANG': 'en_US.UTF-8', 03:43:17 INFO - 'LANGUAGE': 'en_US:en', 03:43:17 INFO - 'LOGNAME': 'cltbld', 03:43:17 INFO - 'MAIL': '/var/mail/cltbld', 03:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:17 INFO - 'MOZ_NO_REMOTE': '1', 03:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:17 INFO - 'NO_EM_RESTART': '1', 03:43:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:17 INFO - 'PWD': '/builds/slave/test', 03:43:17 INFO - 'SHELL': '/bin/bash', 03:43:17 INFO - 'SHLVL': '1', 03:43:17 INFO - 'TERM': 'linux', 03:43:17 INFO - 'TMOUT': '86400', 03:43:17 INFO - 'USER': 'cltbld', 03:43:17 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:17 INFO - '_': '/tools/buildbot/bin/python'} 03:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:17 INFO - Downloading/unpacking psutil>=3.1.1 03:43:17 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 03:43:17 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 03:43:17 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 03:43:17 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 03:43:17 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 03:43:17 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 03:43:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:43:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:19 INFO - Installing collected packages: psutil 03:43:19 INFO - Running setup.py install for psutil 03:43:19 INFO - building 'psutil._psutil_linux' extension 03:43:19 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 03:43:19 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 03:43:20 INFO - building 'psutil._psutil_posix' extension 03:43:20 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 03:43:20 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 03:43:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:20 INFO - Successfully installed psutil 03:43:20 INFO - Cleaning up... 03:43:20 INFO - Return code: 0 03:43:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:20 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:20 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 03:43:20 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 03:43:20 INFO - Using env: {'DISPLAY': ':0', 03:43:20 INFO - 'HOME': '/home/cltbld', 03:43:20 INFO - 'LANG': 'en_US.UTF-8', 03:43:20 INFO - 'LANGUAGE': 'en_US:en', 03:43:20 INFO - 'LOGNAME': 'cltbld', 03:43:20 INFO - 'MAIL': '/var/mail/cltbld', 03:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:20 INFO - 'MOZ_NO_REMOTE': '1', 03:43:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:20 INFO - 'NO_EM_RESTART': '1', 03:43:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:20 INFO - 'PWD': '/builds/slave/test', 03:43:20 INFO - 'SHELL': '/bin/bash', 03:43:20 INFO - 'SHLVL': '1', 03:43:20 INFO - 'TERM': 'linux', 03:43:20 INFO - 'TMOUT': '86400', 03:43:20 INFO - 'USER': 'cltbld', 03:43:20 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:20 INFO - '_': '/tools/buildbot/bin/python'} 03:43:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:43:20 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 03:43:20 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 03:43:20 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 03:43:20 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 03:43:20 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 03:43:20 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 03:43:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:43:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:43:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:43:22 INFO - Installing collected packages: mozsystemmonitor 03:43:22 INFO - Running setup.py install for mozsystemmonitor 03:43:22 INFO - Successfully installed mozsystemmonitor 03:43:22 INFO - Cleaning up... 03:43:22 INFO - Return code: 0 03:43:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:22 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:22 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 03:43:22 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 03:43:22 INFO - Using env: {'DISPLAY': ':0', 03:43:22 INFO - 'HOME': '/home/cltbld', 03:43:22 INFO - 'LANG': 'en_US.UTF-8', 03:43:22 INFO - 'LANGUAGE': 'en_US:en', 03:43:22 INFO - 'LOGNAME': 'cltbld', 03:43:22 INFO - 'MAIL': '/var/mail/cltbld', 03:43:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:22 INFO - 'MOZ_NO_REMOTE': '1', 03:43:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:22 INFO - 'NO_EM_RESTART': '1', 03:43:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:22 INFO - 'PWD': '/builds/slave/test', 03:43:22 INFO - 'SHELL': '/bin/bash', 03:43:22 INFO - 'SHLVL': '1', 03:43:22 INFO - 'TERM': 'linux', 03:43:22 INFO - 'TMOUT': '86400', 03:43:22 INFO - 'USER': 'cltbld', 03:43:22 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:22 INFO - '_': '/tools/buildbot/bin/python'} 03:43:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:22 INFO - Downloading/unpacking jsonschema==2.5.1 03:43:22 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 03:43:22 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 03:43:22 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 03:43:22 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 03:43:22 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 03:43:22 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 03:43:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:43:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:43:24 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 03:43:24 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 03:43:24 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 03:43:24 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 03:43:24 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 03:43:24 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 03:43:24 INFO - Downloading functools32-3.2.3-2.tar.gz 03:43:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:43:25 INFO - warning: no files found matching '*.txt' 03:43:25 INFO - no previously-included directories found matching 'build' 03:43:25 INFO - no previously-included directories found matching 'dist' 03:43:25 INFO - no previously-included directories found matching '.git*' 03:43:25 INFO - Installing collected packages: jsonschema, functools32 03:43:25 INFO - Running setup.py install for functools32 03:43:25 INFO - warning: no files found matching '*.txt' 03:43:25 INFO - no previously-included directories found matching 'build' 03:43:25 INFO - no previously-included directories found matching 'dist' 03:43:25 INFO - no previously-included directories found matching '.git*' 03:43:25 INFO - Successfully installed jsonschema functools32 03:43:25 INFO - Cleaning up... 03:43:25 INFO - Return code: 0 03:43:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:25 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:25 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 03:43:25 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 03:43:25 INFO - Using env: {'DISPLAY': ':0', 03:43:25 INFO - 'HOME': '/home/cltbld', 03:43:25 INFO - 'LANG': 'en_US.UTF-8', 03:43:25 INFO - 'LANGUAGE': 'en_US:en', 03:43:25 INFO - 'LOGNAME': 'cltbld', 03:43:25 INFO - 'MAIL': '/var/mail/cltbld', 03:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:25 INFO - 'MOZ_NO_REMOTE': '1', 03:43:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:25 INFO - 'NO_EM_RESTART': '1', 03:43:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:25 INFO - 'PWD': '/builds/slave/test', 03:43:25 INFO - 'SHELL': '/bin/bash', 03:43:25 INFO - 'SHLVL': '1', 03:43:25 INFO - 'TERM': 'linux', 03:43:25 INFO - 'TMOUT': '86400', 03:43:25 INFO - 'USER': 'cltbld', 03:43:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:25 INFO - '_': '/tools/buildbot/bin/python'} 03:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:43:25 INFO - Cleaning up... 03:43:25 INFO - Return code: 0 03:43:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:25 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:25 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 03:43:25 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 03:43:25 INFO - Using env: {'DISPLAY': ':0', 03:43:25 INFO - 'HOME': '/home/cltbld', 03:43:25 INFO - 'LANG': 'en_US.UTF-8', 03:43:25 INFO - 'LANGUAGE': 'en_US:en', 03:43:25 INFO - 'LOGNAME': 'cltbld', 03:43:25 INFO - 'MAIL': '/var/mail/cltbld', 03:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:25 INFO - 'MOZ_NO_REMOTE': '1', 03:43:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:25 INFO - 'NO_EM_RESTART': '1', 03:43:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:25 INFO - 'PWD': '/builds/slave/test', 03:43:25 INFO - 'SHELL': '/bin/bash', 03:43:25 INFO - 'SHLVL': '1', 03:43:25 INFO - 'TERM': 'linux', 03:43:25 INFO - 'TMOUT': '86400', 03:43:25 INFO - 'USER': 'cltbld', 03:43:25 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:25 INFO - '_': '/tools/buildbot/bin/python'} 03:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:25 INFO - Downloading/unpacking blobuploader==1.2.4 03:43:25 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 03:43:25 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 03:43:25 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 03:43:25 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 03:43:25 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 03:43:25 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 03:43:27 INFO - Downloading blobuploader-1.2.4.tar.gz 03:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:43:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:43:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:43:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:43:28 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 03:43:28 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 03:43:28 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 03:43:28 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 03:43:28 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 03:43:28 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 03:43:28 INFO - Downloading docopt-0.6.1.tar.gz 03:43:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:43:28 INFO - Installing collected packages: blobuploader, requests, docopt 03:43:28 INFO - Running setup.py install for blobuploader 03:43:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:43:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:43:28 INFO - Running setup.py install for requests 03:43:28 INFO - Running setup.py install for docopt 03:43:28 INFO - Successfully installed blobuploader requests docopt 03:43:28 INFO - Cleaning up... 03:43:28 INFO - Return code: 0 03:43:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:28 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')]} 03:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:28 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')]} 03:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:28 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:28 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 03:43:28 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 03:43:28 INFO - Using env: {'DISPLAY': ':0', 03:43:28 INFO - 'HOME': '/home/cltbld', 03:43:28 INFO - 'LANG': 'en_US.UTF-8', 03:43:28 INFO - 'LANGUAGE': 'en_US:en', 03:43:28 INFO - 'LOGNAME': 'cltbld', 03:43:28 INFO - 'MAIL': '/var/mail/cltbld', 03:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:28 INFO - 'MOZ_NO_REMOTE': '1', 03:43:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:28 INFO - 'NO_EM_RESTART': '1', 03:43:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:28 INFO - 'PWD': '/builds/slave/test', 03:43:28 INFO - 'SHELL': '/bin/bash', 03:43:28 INFO - 'SHLVL': '1', 03:43:28 INFO - 'TERM': 'linux', 03:43:28 INFO - 'TMOUT': '86400', 03:43:28 INFO - 'USER': 'cltbld', 03:43:28 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:28 INFO - '_': '/tools/buildbot/bin/python'} 03:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:29 INFO - Running setup.py (path:/tmp/pip-_WnceV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:29 INFO - Running setup.py (path:/tmp/pip-y9BAcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:29 INFO - Running setup.py (path:/tmp/pip-l6eDjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:29 INFO - Running setup.py (path:/tmp/pip-jQ9hrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:29 INFO - Running setup.py (path:/tmp/pip-0vdyCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:29 INFO - Running setup.py (path:/tmp/pip-aqbuMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:29 INFO - Running setup.py (path:/tmp/pip-U0VSyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:29 INFO - Running setup.py (path:/tmp/pip-9DieGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:29 INFO - Running setup.py (path:/tmp/pip-uTGZQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:29 INFO - Running setup.py (path:/tmp/pip-tCe3jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:29 INFO - Running setup.py (path:/tmp/pip-DMrSP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:29 INFO - Running setup.py (path:/tmp/pip-59hafQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:30 INFO - Running setup.py (path:/tmp/pip-BpRBHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:30 INFO - Running setup.py (path:/tmp/pip-kVFt8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:30 INFO - Running setup.py (path:/tmp/pip-u9Onp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:30 INFO - Running setup.py (path:/tmp/pip-3VzEK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:30 INFO - Running setup.py (path:/tmp/pip-Hub4VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:43:30 INFO - Running setup.py install for manifestparser 03:43:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:43:30 INFO - Running setup.py install for mozcrash 03:43:30 INFO - Running setup.py install for mozdebug 03:43:30 INFO - Running setup.py install for mozdevice 03:43:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:43:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:43:30 INFO - Running setup.py install for mozfile 03:43:30 INFO - Running setup.py install for mozhttpd 03:43:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:43:30 INFO - Running setup.py install for mozinfo 03:43:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Running setup.py install for mozInstall 03:43:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Running setup.py install for mozleak 03:43:31 INFO - Running setup.py install for mozlog 03:43:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Running setup.py install for moznetwork 03:43:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Running setup.py install for mozprocess 03:43:31 INFO - Running setup.py install for mozprofile 03:43:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Running setup.py install for mozrunner 03:43:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:43:31 INFO - Running setup.py install for mozscreenshot 03:43:31 INFO - Running setup.py install for moztest 03:43:32 INFO - Running setup.py install for mozversion 03:43:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:43:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:43:32 INFO - Cleaning up... 03:43:32 INFO - Return code: 0 03:43:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:32 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')]} 03:43:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:32 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')]} 03:43:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:32 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:32 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 03:43:32 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 03:43:32 INFO - Using env: {'DISPLAY': ':0', 03:43:32 INFO - 'HOME': '/home/cltbld', 03:43:32 INFO - 'LANG': 'en_US.UTF-8', 03:43:32 INFO - 'LANGUAGE': 'en_US:en', 03:43:32 INFO - 'LOGNAME': 'cltbld', 03:43:32 INFO - 'MAIL': '/var/mail/cltbld', 03:43:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:32 INFO - 'MOZ_NO_REMOTE': '1', 03:43:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:32 INFO - 'NO_EM_RESTART': '1', 03:43:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:32 INFO - 'PWD': '/builds/slave/test', 03:43:32 INFO - 'SHELL': '/bin/bash', 03:43:32 INFO - 'SHLVL': '1', 03:43:32 INFO - 'TERM': 'linux', 03:43:32 INFO - 'TMOUT': '86400', 03:43:32 INFO - 'USER': 'cltbld', 03:43:32 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:32 INFO - '_': '/tools/buildbot/bin/python'} 03:43:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:32 INFO - Running setup.py (path:/tmp/pip-Lzd4K4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:32 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)) 03:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:32 INFO - Running setup.py (path:/tmp/pip-jhtnAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:32 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)) 03:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:32 INFO - Running setup.py (path:/tmp/pip-5mlM3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:32 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)) 03:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:32 INFO - Running setup.py (path:/tmp/pip-eKmcKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:32 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)) 03:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:32 INFO - Running setup.py (path:/tmp/pip-dJD10f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:32 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)) 03:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:32 INFO - Running setup.py (path:/tmp/pip-AMwSdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:32 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)) 03:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:32 INFO - Running setup.py (path:/tmp/pip-YqmayZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:32 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)) 03:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:32 INFO - Running setup.py (path:/tmp/pip-Jckl2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:32 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)) 03:43:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:32 INFO - Running setup.py (path:/tmp/pip-SKzvC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:33 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)) 03:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:33 INFO - Running setup.py (path:/tmp/pip-ifjeoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:33 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)) 03:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:33 INFO - Running setup.py (path:/tmp/pip-NOBOMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:33 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)) 03:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:33 INFO - Running setup.py (path:/tmp/pip-EPRUDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:33 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)) 03:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:33 INFO - Running setup.py (path:/tmp/pip-NOy6Eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:33 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)) 03:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:33 INFO - Running setup.py (path:/tmp/pip-aURBJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:33 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)) 03:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:33 INFO - Running setup.py (path:/tmp/pip-qF5SiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:33 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)) 03:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:33 INFO - Running setup.py (path:/tmp/pip-hk_cNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:33 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)) 03:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:33 INFO - Running setup.py (path:/tmp/pip-eSNeAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:33 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)) 03:43:33 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)) 03:43:33 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)) 03:43:33 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)) 03:43:33 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)) 03:43:33 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)) 03:43:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:43:33 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 03:43:33 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 03:43:33 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 03:43:33 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 03:43:33 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 03:43:33 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 03:43:35 INFO - Downloading blessings-1.6.tar.gz 03:43:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:43:35 INFO - Installing collected packages: blessings 03:43:35 INFO - Running setup.py install for blessings 03:43:35 INFO - Successfully installed blessings 03:43:35 INFO - Cleaning up... 03:43:35 INFO - Return code: 0 03:43:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:43:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:35 INFO - Reading from file tmpfile_stdout 03:43:35 INFO - Current package versions: 03:43:35 INFO - argparse == 1.2.1 03:43:35 INFO - blessings == 1.6 03:43:35 INFO - blobuploader == 1.2.4 03:43:35 INFO - docopt == 0.6.1 03:43:35 INFO - functools32 == 3.2.3-2 03:43:35 INFO - jsonschema == 2.5.1 03:43:35 INFO - manifestparser == 1.1 03:43:35 INFO - mozInstall == 1.12 03:43:35 INFO - mozcrash == 0.17 03:43:35 INFO - mozdebug == 0.1 03:43:35 INFO - mozdevice == 0.48 03:43:35 INFO - mozfile == 1.2 03:43:35 INFO - mozhttpd == 0.7 03:43:35 INFO - mozinfo == 0.9 03:43:35 INFO - mozleak == 0.1 03:43:35 INFO - mozlog == 3.3 03:43:35 INFO - moznetwork == 0.27 03:43:35 INFO - mozprocess == 0.23 03:43:35 INFO - mozprofile == 0.28 03:43:35 INFO - mozrunner == 6.12 03:43:35 INFO - mozscreenshot == 0.1 03:43:35 INFO - mozsystemmonitor == 0.3 03:43:35 INFO - moztest == 0.7 03:43:35 INFO - mozversion == 1.4 03:43:35 INFO - psutil == 3.1.1 03:43:35 INFO - requests == 1.2.3 03:43:35 INFO - wsgiref == 0.1.2 03:43:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43: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')]} 03:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43: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')]} 03:43:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:35 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:35 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 03:43:35 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 03:43:35 INFO - Using env: {'DISPLAY': ':0', 03:43:35 INFO - 'HOME': '/home/cltbld', 03:43:35 INFO - 'LANG': 'en_US.UTF-8', 03:43:35 INFO - 'LANGUAGE': 'en_US:en', 03:43:35 INFO - 'LOGNAME': 'cltbld', 03:43:35 INFO - 'MAIL': '/var/mail/cltbld', 03:43:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:35 INFO - 'MOZ_NO_REMOTE': '1', 03:43:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:35 INFO - 'NO_EM_RESTART': '1', 03:43:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:35 INFO - 'PWD': '/builds/slave/test', 03:43:35 INFO - 'SHELL': '/bin/bash', 03:43:35 INFO - 'SHLVL': '1', 03:43:35 INFO - 'TERM': 'linux', 03:43:35 INFO - 'TMOUT': '86400', 03:43:35 INFO - 'USER': 'cltbld', 03:43:35 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:35 INFO - '_': '/tools/buildbot/bin/python'} 03:43:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:36 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)) 03:43:36 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)) 03:43:36 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)) 03:43:36 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)) 03:43:36 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)) 03:43:36 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)) 03:43:36 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)) 03:43:36 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)) 03:43:36 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)) 03:43:36 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)) 03:43:36 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)) 03:43:36 INFO - Cleaning up... 03:43:36 INFO - Return code: 0 03:43:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:43:36 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')]} 03:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:36 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')]} 03:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:36 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 0x23e0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, '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': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', '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 03:43:36 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 03:43:36 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 03:43:36 INFO - Using env: {'DISPLAY': ':0', 03:43:36 INFO - 'HOME': '/home/cltbld', 03:43:36 INFO - 'LANG': 'en_US.UTF-8', 03:43:36 INFO - 'LANGUAGE': 'en_US:en', 03:43:36 INFO - 'LOGNAME': 'cltbld', 03:43:36 INFO - 'MAIL': '/var/mail/cltbld', 03:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:36 INFO - 'MOZ_NO_REMOTE': '1', 03:43:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:36 INFO - 'NO_EM_RESTART': '1', 03:43:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:36 INFO - 'PWD': '/builds/slave/test', 03:43:36 INFO - 'SHELL': '/bin/bash', 03:43:36 INFO - 'SHLVL': '1', 03:43:36 INFO - 'TERM': 'linux', 03:43:36 INFO - 'TMOUT': '86400', 03:43:36 INFO - 'USER': 'cltbld', 03:43:36 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:36 INFO - '_': '/tools/buildbot/bin/python'} 03:43:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:43:36 INFO - Cleaning up... 03:43:36 INFO - Return code: 0 03:43:36 INFO - Running post-action listener: _resource_record_post_action 03:43:36 INFO - Running post-action listener: _start_resource_monitoring 03:43:36 INFO - Starting resource monitoring. 03:43:36 INFO - [mozharness: 2016-09-13 10:43:36.474482Z] Finished create-virtualenv step (success) 03:43:36 INFO - [mozharness: 2016-09-13 10:43:36.474909Z] Running install step. 03:43:36 INFO - Running pre-action listener: _resource_record_pre_action 03:43:36 INFO - Running main action method: install 03:43:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:36 INFO - Reading from file tmpfile_stdout 03:43:36 INFO - Detecting whether we're running mozinstall >=1.0... 03:43:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:43:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:43:36 INFO - Reading from file tmpfile_stdout 03:43:36 INFO - Output received: 03:43:36 INFO - Usage: mozinstall [options] installer 03:43:36 INFO - Options: 03:43:36 INFO - -h, --help show this help message and exit 03:43:36 INFO - -d DEST, --destination=DEST 03:43:36 INFO - Directory to install application into. [default: 03:43:36 INFO - "/builds/slave/test"] 03:43:36 INFO - --app=APP Application being installed. [default: firefox] 03:43:36 INFO - mkdir: /builds/slave/test/build/application 03:43:36 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'] 03:43:36 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 03:43:53 INFO - Reading from file tmpfile_stdout 03:43:53 INFO - Output received: 03:43:53 INFO - /builds/slave/test/build/application/firefox/firefox 03:43:53 INFO - Running post-action listener: _resource_record_post_action 03:43:53 INFO - [mozharness: 2016-09-13 10:43:53.444151Z] Finished install step (success) 03:43:53 INFO - [mozharness: 2016-09-13 10:43:53.444432Z] Running run-tests step. 03:43:53 INFO - Running pre-action listener: _resource_record_pre_action 03:43:53 INFO - Running main action method: run_tests 03:43:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:43:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:43:53 INFO - Python 2.7.3 03:43:53 INFO - Return code: 0 03:43:53 INFO - grabbing minidump binary from tooltool 03:43: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')]} 03:43:53 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 0x269f3b0>, '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 0x26a02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26a0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:43:53 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 03:43:53 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 03:43:53 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 03:43:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:43:53 INFO - Return code: 0 03:43:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:43:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:43:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:43:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:43:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:43:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:43:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:43:53 INFO - Using env: {'DISPLAY': ':0', 03:43:53 INFO - 'HOME': '/home/cltbld', 03:43:53 INFO - 'LANG': 'en_US.UTF-8', 03:43:53 INFO - 'LANGUAGE': 'en_US:en', 03:43:53 INFO - 'LOGNAME': 'cltbld', 03:43:53 INFO - 'MAIL': '/var/mail/cltbld', 03:43:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:43:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:53 INFO - 'MOZ_NO_REMOTE': '1', 03:43:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:53 INFO - 'NO_EM_RESTART': '1', 03:43:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:53 INFO - 'PWD': '/builds/slave/test', 03:43:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:43:53 INFO - 'SHELL': '/bin/bash', 03:43:53 INFO - 'SHLVL': '1', 03:43:53 INFO - 'TERM': 'linux', 03:43:53 INFO - 'TMOUT': '86400', 03:43:53 INFO - 'USER': 'cltbld', 03:43:53 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839', 03:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:53 INFO - '_': '/tools/buildbot/bin/python'} 03:43:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:43:53 INFO - mozversion application_buildid: 20160913004005 03:43:53 INFO - mozversion application_changeset: 696a981b6d534a7015d6f2ba3d2379af620e9b26 03:43:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:43:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:43:53 INFO - mozversion application_name: Firefox 03:43:53 INFO - mozversion application_remotingname: firefox-dev 03:43:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:43:53 INFO - mozversion application_vendor: Mozilla 03:43:53 INFO - mozversion application_version: 50.0a2 03:43:53 INFO - mozversion platform_buildid: 20160913004005 03:43:53 INFO - mozversion platform_changeset: 696a981b6d534a7015d6f2ba3d2379af620e9b26 03:43:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:43:53 INFO - mozversion platform_version: 50.0a2 03:43:53 INFO - using testdate: 1473763433 03:43:53 INFO - actual date: 1473763433 03:43:53 INFO - starting webserver on 'localhost:42886' 03:43:53 INFO - SUITE-START | Running 2 tests 03:43:53 INFO - TEST-START | dromaeo_css 03:43:53 INFO - operating with platform_type : linux_ 03:43:53 INFO - Initialising browser for dromaeo_css test... 03:43:53 INFO - TEST-INFO | started process 3450 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgNztqB/profile http://localhost:42886/getInfo.html) 03:43:57 INFO - PROCESS | 3450 | __metrics Screen width/height:1600/1200 03:43:57 INFO - PROCESS | 3450 | colorDepth:24 03:43:57 INFO - PROCESS | 3450 | Browser inner width/height: 1024/697 03:43:57 INFO - PROCESS | 3450 | __metrics 03:43:58 INFO - PROCESS | 3450 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:43:58 INFO - PROCESS | 3450 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:43:59 INFO - TEST-INFO | 3450: exit 0 03:43:59 INFO - Browser initialized. 03:43:59 INFO - Running cycle 1/1 for dromaeo_css test... 03:43:59 INFO - TEST-INFO | started process 3540 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgNztqB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:11 INFO - PROCESS | 3540 | RSS: Main: 145997824 03:44:11 INFO - PROCESS | 3540 | 03:45:02 INFO - PROCESS | 3540 | 1473763502014 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] 03:47:10 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:42886/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42886/tests/dromaeo/cssquery-ext.html) 03:47:11 INFO - PROCESS | 3540 | RSS: Main: 230191104 03:47:11 INFO - PROCESS | 3540 | 03:50:14 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:42886/tests/dromaeo/cssquery-ext.html (next: http://localhost:42886/tests/dromaeo/cssquery-jquery.html) 03:50:14 INFO - PROCESS | 3540 | RSS: Main: 235548672 03:50:14 INFO - PROCESS | 3540 | 03:53:16 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:42886/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42886/tests/dromaeo/cssquery-mootools.html) 03:53:17 INFO - PROCESS | 3540 | RSS: Main: 174845952 03:53:17 INFO - PROCESS | 3540 | 03:56:19 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:42886/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42886/tests/dromaeo/cssquery-prototype.html) 03:56:20 INFO - PROCESS | 3540 | RSS: Main: 175484928 03:56:20 INFO - PROCESS | 3540 | 03:59:22 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:42886/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42886/tests/dromaeo/cssquery-yui.html) 03:59:23 INFO - PROCESS | 3540 | RSS: Main: 175915008 03:59:23 INFO - PROCESS | 3540 | 04:02:25 INFO - PROCESS | 3540 | Cycle 1(1): loaded http://localhost:42886/tests/dromaeo/cssquery-yui.html (next: http://localhost:42886/tests/dromaeo/cssquery-dojo.html) 04:02:25 INFO - PROCESS | 3540 | RSS: Main: 263839744 04:02:25 INFO - PROCESS | 3540 | 04:02:25 INFO - PROCESS | 3540 | __start_tp_report 04:02:25 INFO - PROCESS | 3540 | _x_x_mozilla_page_load 04:02:25 INFO - PROCESS | 3540 | _x_x_mozilla_page_load_details 04:02:25 INFO - PROCESS | 3540 | |i|pagename|runs| 04:02:25 INFO - PROCESS | 3540 | |0;dojo.html;3224.016674914151;3240.0441869648457;3263.4288999425094;3281.753868459866;3316.3698897209542;5586.525145362644;5635.718214089295;5663.603547751659;5678.318601767794;5704.629199102061;5643.379228284885;5685.857853553653;5686.402927692595;5690.146478028298;5690.317161940573;4900.411950565937;4971.676841005341;5130.589552835783;5135.82024629139;5183.144781111112;5072.315237442954;5114;5120.4623514530995;5122.308488354061;5127.3078134451935;5045.091883460976;5082.0598189334905;5084.898302033957;5112.821051263207;5119.334486516747;5211.4788521147775;5238.528532432099;5241.9213711794355;5245.711485868275;5278.5249327560705;4877.731724755137;5008.724520151391;5013.89972200554;5117.309163262949;5122.129238029506;5584.078627026536;5611.3546942101775;5617.522510586564;5642.633228840154;5650.5479561634975;6149.538784591129;6153.107799369066;6153.446189842891;6155.569110162291;6158.692065396713;5924.496417804448;5967.015442451991;5994.760209591611;6041.72812223451;6170.81487555374;5588.944110558924;5633.436656334375;5640.32316122068;5671;5677.602567820296;5874.265716785402;6167.321594624587;6168.8766224675255;6170.81487555365;6199.721012554446;5830.154627578977;5830.62100963436;5846.123081537803;5888.028475301613;5925.466707996301;5975.880482390415;5977.163197152402;5980.401959804072;5982.940170598238;5983.551233657406;5795.507381872504;5958.493528050164;5964.671943043174;5971.970140149271;5973.850653733692;6015.488683461867;6045.244344456943;6074.605150665193;6086.604370715978;6094.725737341831;4637.234856248749;4641.721496710208;4695.694779839299;4696.577308042292;4720.386349774576;4081.10215752534;4176.686748493875;4196.097838964637;4221.050263690645;4222.59885310895;5645.181448689917;5646.040173170571;5661.7735290588735;5741.339745929272;5773.1051686988585;5968.313643930916;6012.458878700851;6018.849528761816;6024.518038556905;6030.698465076816;5570.498655121059;5653.971730141323;5655.208270842086;5716.57125715573;5721.342045664718;5165.767540460688;5202.193659982709;5229.686218826882;5272.2355258487305;5305.1776974569;6291.150694656163;6300.2754683212015;6303.117563541108;6303.747850086037;6305.464035556937;5824.5631577631;6061.212042434456;6069.302030266399;6077.331493535619;6079.148919151234;6178.505719542336;6230.719617617127;6244.344343844013;6313;6350.872982540416;6004.309504407049;6005.279366476051;6008.098785182083;6019.067044608093;6024.096385542204;5778.94221057784;6113.786017489367;6125.84685382869;6130.601510901777;6132.509399247961;5887.587868849139;5940.1683764272175;5954.791582294599;5994.100884867305;6043.2748070231855;5910.143029260819;5920.407959204045;5941.792037278675;5944.375840536854;5947;5531.280933478622;5879.265091863517;5916.349201587908;5919.4968427684125;5927.140564618193;5950.34546199909;5984.898569887665;5995.190649262294;6126.019836826086;6129.049997250433;5927.229460170151;5943.316518600243;5945.19739835134;5945.732442040032;5948.702564871653 04:02:25 INFO - PROCESS | 3540 | |1;ext.html;608.9360617135201;632.1024145713087;667.7128834601123;674.3492529708833;688.2257460357098;34315.48526772097;44679;45908;45912.3113153303;45936;47766.76116619421;48230.75884620572;48411.27383089256;48429.27356089662;48495.51504484954;8737;9514.714558563253;9655.27585431092;9659.80680386392;9659.855102173438;8755.080716524768;9065.18413342799;9068.637254509844;11194.496247668874;11494.36780977046;11785.410729463492;11836.053115750763;11838.289702617827;11842.940785296061;11856.466459009322;11593.304401735923;11620.767584648343;11661.941690291536;11674.474648640746;11688.298702077902;11403.486843092081;11448.370339631318;11453.713657158638;11461.828072578917;11563.88436115645;46877;47284.7635761819;47548.049039019024;47742.28386574204;47786;3584.5877724061806;3657.3416784978695;3680.6319368063246;3732.869349572779;3968.583298753647;35607.82196089003;35621.64378356209;35800.31497637661;35939.28121437583;36359.27281454356;4786.44955830077;4838.612910967114;4845.297431872359;4845.685030473008;4966.453690094087;386063.9999999972;386773;387009;387830;388363;37501.81249093792;38140;38533.807330963165;67027.66486167636;67420.6628966852;41666.58333416688;41720.37419438711;42029;42091.15817683691;42146.15707685829;15462.14958177311;15513.379464821506;15547.067175969478;15586.454474093352;15594.610134746496;11576.24754390962;12133.817992730115;12201.328926909106;12202.084843636763;12206.206596571194;9072.228860546928;9173.807405037302;9275.58259878307;9483.430994140374;9484.336096473318;3492.406290930548;3503.3168532136497;3597.8021208833766;3614.168741189541;3615.638436156416;1069.3904474449644;1132.8923752243525;1149.51720277482;1175.9529618815152;1194.9066604057364;35058;35384.29231415409;35414.64585354165;35470.467942980875;35616.64383356185;1173.2491205628405;1217.5129948020863;1227.711487800384;1248.875112488762;1267.5880338889897;17976.678473355325;18335.35826246075;18433.723494147598;18618.255269789326;18739.90630046841;560117;566476;567313;568200;568605;66517;67055;67175.66412167907;67190.66404667945;67200.66399668068;1321.8017297405465;1380.7790753479596;1412.894032947533;1476.438953197778;1490.8434614365553;30830.691693082783;36283.637163628024;36617.81691091634;36925.446118308784;36978;56792.71603641955;57180.999999998334;57328.1400778997;57333;57396.42603574078;453862;454432;455835;456147;457119;30783;31493.527597085606;31587.21031974219;32015.83992080025;33571.49642755312;53194;53823;53971.730141350614;55150.72424637851;55735.16397253963;45243.77378113088;46323.30515042209;46438.767806162105;47195.52804471911;47493.762531188506;45656.54343456523;45674;46718.53281467278;47273.763631183;47393.05213895772;45369;46023.76988115038;46679.53320466752;46836;48523.75738121287;38638;41201.1759764809;41204.79397602993;41445.585544144174;42089;30439.695603043685;30439.847800760854;30681.846590767796;30985.929424565216;31013.379732405676 04:02:25 INFO - PROCESS | 3540 | |2;jquery.html;391.5692737988215;413.2870797873666;429.3795465552277;434.8282428440766;435.65365534400166;15071.321790519425;20189.798102018976;20490.28284010058;20505.07727152278;20619.69070463944;19878.20487180515;19922;19981.60036799267;19999.300024499145;20203.39389818304;5753.942460575404;5822.592418530715;6026.5178785697035;6067.726952287136;6119.326874043852;6163.414475624809;6163.845903852395;6173.876522469548;6188.752449901998;6202;6228.19033525641;6529.640869752162;6570.770023049195;6578.671066446683;6579.506537009719;5765.106408506646;5997.790077347272;6569.178852643419;6664.633445160514;6695.2969938156275;5922.170896074553;5935.970320148414;5942.910856337158;5948.613340132921;5950;13396.665083372896;21828.126874924983;23640.527189456116;23671.763282367127;23854.642180367307;2285.5086156476273;2293.4037150341032;2338.5673650374733;2402.9879850600637;2473.839200451983;10511.632092876713;10570.630027948984;10621.787564248749;10825.729356766065;11181.161412893984;2002.819746222847;2059.1145807302855;2066.917323307081;2075.0351086744586;2102.568973360457;66758.56597411622;71444.64277678578;74612.25387746107;88913.11086889113;93259.06740932571;11183.664490065312;11361.431928403546;11711.238769479956;11956.521739130338;12131.393430328448;16454.341826326814;16475.505734827973;16477.34090636361;16505.83494165043;16509.257083431276;7986.361091112697;8036.839263214703;8368;8370.246677798905;8466.238038576557;7524.774256772305;7683.116441609175;7705.267999540147;7723.575203363756;8018.118007019223;4581.862544123682;4591.288350305708;5032.094223039815;5045.142325804622;5170.638055336166;2057.98971005144;2075.802798734118;2103.1902717453586;2121.5332626822074;2123.50097727034;970.4807927758616;970.6069042037985;980.1374790184734;982.5038355630361;1062.824633935392;18404.447866564024;20053;20219.29232476856;20234.898825505777;20387.184512619624;1003.1673710820002;1003.4481035430484;1005.8189525885265;1008.5159123620704;1029.6190409548365;10230.28388012832;10912.56349746017;10927.344359338465;11019.063379612822;11082.722931926604;532958;543178;549193;555514;565326;94926.52536737273;98089.50955245178;99117;100067;103151.48424257981;987.7728122531714;1014.8071866345371;1033.256055639938;1034.1726618705006;1045.8065257927155;17295.13524323804;17419.074321184085;17805.732914006043;17843.821561784214;17987.820121798613;45486.772566136955;45553.77223113863;45610.315845261684;46152;46263.768681156376;61558;62185.37814621796;62437;62664.37335626768;63887.36112638814;8797.296216302553;8873.645054198018;9074.274058075207;9194.678186263449;9254.259659227111;12651.93674031624;12822.743545128858;13168.275744834125;13267.734645306848;13610;11589.942050289694;11734.41327933617;11765.529378825093;11919.3444360561;12116.515339386679;10821.296615719953;10837.512311946139;10888.836667450152;11671.066314694974;12162.209456385306;11243.4378281084;11407.315561066363;11667.416629168338;11768.764624707286;11966;8853.380263381501;8914.153155450222;8986.101389860962;9056.637734490547;9060.320475964198;8687;8727;8855.73432797017;8870.467771933705;9335.719928402159 04:02:25 INFO - PROCESS | 3540 | |3;mootools.html;1411.9647008824784;1579.4392990488377;1603.607116256517;1806.1872157529115;1916.1568909679731;9009.819803603925;9442.763930901723;9504.572294246758;9810.362326448776;9811.607535698582;9617.42295462271;9720.222382209424;9726.124648781626;9734.805303893918;9739.756506087333;2400.543896659638;2434.866082365469;2435.914742983996;2436.8050555955483;2446.0582675669816;2421.3583400398907;2427.9393015174674;2470.962935555968;2472.4066224106264;2474.8515089094712;2369.4194922244073;2372.7271363793125;2418.238254949685;2450.019992003195;2452.3256104571296;2361.3388251289666;2366.0417530900013;2371.7903869026836;2371.905123795046;2424.793892519138;2388.498415332765;2413.4931664350506;2436.671049408325;2440.8169387295848;2442;9637;9646.372985755903;9743.171830394425;9758.707238782848;9775.951120244425;5507.669539827623;5620.522255608278;5639.464250896158;5641.18202860587;5647.152927060933;3650.0692323457615;3660.6705396514176;3682.705383569308;3714.312852122366;3725.068732816796;1240.3488168711408;1265.1839563481503;1267.0687045021903;1279.2708156350884;1327.1970457872944;13874.65313367174;13882;13917.443302267897;13944.860551394559;13955.092918960134;4731.848636594417;4788.329633851264;4805.7597120143855;4842.806287748451;4938;10700.464976751131;10736.946315268373;10740.355578665305;10748.301360411548;10787.83818242727;5496.422875598085;5498.395176530578;5504.504594586427;5507.449255074461;5512.228288039678;4601.470830854495;4679.508651591602;4686.414198225222;5007.298978143063;5070.138076527;4244.681648876347;4261.232978063978;4369.978150109229;4446.377507149002;4497.955020449819;1273.7388835288743;1274.8278982337265;1299.493197652916;1301.3688361144837;1305.0864394923592;1018.6638409324905;1019.9898001020043;1023.7184774187158;1030.1346868630237;1034.7671773850823;6169.907451388232;6177.752889884354;6254.312025677172;6344.555881088372;6472.385123413268;470.4495739984227;472.12892213865365;474.77922765914013;476.69729721627016;477.1959248666009;4076.4089207065404;4086.7547947123267;4092.6930480214105;4122.361034039729;4125.876223713293;11805.468753906094;12160.452779624939;12251.877481225252;12268.141230113806;12274.570390036306;12164.574239901562;12169.20900141488;12175.269483830998;12178.878211217952;12207.389630518439;8514;8741.169588889044;8773.385862989657;8777.868331975025;9034.774130646787;6232.345603711727;6393.200849893763;6753.15585551806;6901.344372284625;6978.337057979484;11137.944310278397;11196.384198868815;11382.943085284853;11385.146114041314;11529.82705259405;9665.130138287588;11020;11593.24643898144;11625.825612615987;11741.76516469683;8267.131951145044;8372.037215720271;8691.739247822576;8708.60811263482;8710.564471776512;9170.633174672937;9454.480003599869;9470.14768670823;9488.051194880456;9488.715338539854;8190.221928916743;8233.79415514617;8250.46371985819;8298.377621678277;8325.25072743456;8019.318357939407;8180.67277308901;8214.424990250625;8218.589070546568;8234.05308389543;7063.117110361205;7064.964675176591;7141.607211603412;7203.71185152588;7220.3862671671395;5076.36545431821;5139.152039913372;5190.844274671766;5212.426633070283;5277.445868183785;5601.80393686219;5635.577331700057;5722.542196624343;5777.364489906023;5801.1878337032 04:02:25 INFO - PROCESS | 3540 | |4;prototype.html;342.8817058114951;350.89297764181924;355.0343066858145;356.90185199070254;362.14894996757624;5577.916331255029;5619.943800561999;5621.184928185411;5627.43725627437;5640.633358831673;5496.615236933416;5532.944670553294;5533.197686335479;5546.86132846679;5561.4716601922855;4263.4031235627035;4342.54403287655;4365.4106695596165;4380.824767009316;4404.427424434819;4325.956740432587;4334.761588112652;4362.25841606926;4368.628666563346;4383.232934236512;4052.1490486997764;4302.763348015857;4307.353896915456;4308.913821723564;4343.370211319357;4142.150859073898;4324.437823083011;4326.653867690608;4351.97824010878;4356.324769660708;3865.632764887331;3871.1483473635753;3873.2447172801317;3885.3977633466857;3887.844486220548;5009.524095210949;5324.547413469821;5344.118220493614;5371.274877891474;5384.59615528833;4498.617617502517;4854.199057155565;4887.242477416006;4926.339776520675;4941.481144479814;5696.458836410576;5702.45826646468;5712.8000519982015;5728.484436400702;5759;3945.230680017398;4127.442795222637;4265.189613973335;4268.146370725867;4271.103068355649;5923.17075609415;5935.465808077251;5951.58338916276;5969.671668058255;5981.222441082631;4266.573342665771;4278.636315913182;4300.935485967713;4312.331588603772;4315.244832154361;4148.543660197376;4350.760708161019;4371.803268852909;4374.759388233614;4381.627561657231;4923.556879880828;4925.285833554115;4944.431390390151;4974.90050198994;4997.400311962515;5821.388754180834;5885.705714714247;5893.174955506232;5923.881522369528;5932.673702946379;1430.5551393092842;1494.5591050640087;1513.137511618367;1515.2575238133236;1529.9388024478897;951.5004622573205;965.6909788867495;982.7985263021063;993.8956409577034;994.4132961552718;4963.801447942042;4982.202847544376;4985.601151907838;5006.148954677641;5017.494751574586;5118.59051275897;5125.333706618116;5155.948440515547;5159.7678104485385;5168.3797944246935;4928.778204980785;4940.258961155855;4973.602111831045;5184.507471790174;5344.625876188629;2750.518659234626;3123.6251649801716;3134.0754477429214;3135.5923729914966;3147.716705496516;6093.96953015241;6109.266887973472;6125.081237814275;6135.32511423743;6158.876822463615;5828.424686620982;5841.532677385799;5944.910826337608;5950.196723442366;5976.372480889444;5989.173302142888;6052.6671033092725;6061.151438798572;6078.237311179797;6080.391960803972;5858.648481091148;5866.911996319973;5879.647221166572;5913.172155898092;5918.141869428822;5787.276590426197;5814.94185058144;5817.70911454434;5820.185174075548;5827.621204621686;5844.211031510862;6024.246969128859;6048.516118710405;6093.9085913712215;6095.573309868444;4038.7980600970423;4090.795460227036;4192.329227323614;4272.6154646082;4284.207421627009;5860.824175274749;5917.112433135064;5917.378675239038;5922.703864806656;5934.495567876606;5673.8297851064535;5719.570107473131;5803.216565763568;5831.679257640871;5841.824745257649;5754.223179870832;5767.740451679769;5784.971075144766;5796.971015144897;5822.970885145547;5554.527865131509;5725.484706376279;5737.48362647347;5741.91387129185;5774.191613174243;5691.8861622768145;5733.59864809476;5755.971220143872;5757.0788673811985;5772.1630363596205;5617.915731263953;5642.463965923231;5689.715514224355;5692.459216374438;5759.366469688414 04:02:25 INFO - PROCESS | 3540 | |5;yui.html;755.9357454616357;775.9806004849878;811.9756407307783;820.3888103362996;873.3493547307255;16108.677826443463;16742.24659890305;17312.04783736894;17342.393016244434;17525.123743812805;15175;15513.84486155138;15540.533783986499;15618.375264989416;15622.218889055559;724.6087112958995;858.5921687198562;870.3602851903846;879.1647934462254;880.7842078690729;878.3236907581158;887.1704955866253;888.8577827547593;888.9911100888972;890.3900827932854;937.653068364706;938.629241449627;939.1876027236425;940.2571968145157;943.1888575824785;750.3471979377922;897.9461232326081;928.8513837785955;929.4748467116058;930.3812964378681;683.0369179456942;684.1414025398094;686.5297271369078;686.5640318397803;686.8523267497462;15541.533753987398;15549.689006219925;15572.221388930508;15583.987040842308;15596.064236145867;14384.856151438456;14931.402743890232;15210.923945380313;15412.306446209948;15751.2124393781;14320.64198395038;14379.71240575193;14381.496647617283;14641.70716585673;14767.409303627843;4555.840545580922;4579.839705610321;4598.655100867415;4638.582527572501;4686.695364801277;87874;89124.10875891223;92234.07765922321;92873.53563232208;93050.53474732583;12036.45835937385;12119.39403029845;12238.81641775374;12377.566785162477;12457.252564846138;23174;23235.419114522272;23263;23289;23364.883175584015;9217.170454659114;9293.860592091123;9331.253499720005;9368.531573421302;9769.071938165864;5023;5115.488451154929;5137.897242055138;5231.764570594332;5311.628186026941;3541.858325666996;3602.3875941090078;3643.872464463731;3659.87190448333;3682.2451397463456;625.8122563231012;636.2333388267127;638.5753473939778;649.6297110646885;651.2933467188059;661.7683810666229;709.4501761135058;718.1418205244682;724.1780579042825;724.8622761675265;9795.559199835881;9947.204223662235;10092.44491552957;10201.642942496977;10323.896761032294;659.3867703036223;675.4326365852609;721.3579913876654;731.989020164698;738.1990540263766;4549.8307709104865;4769.189237829577;4799.616030717534;4838.516148385203;4851.100646912877;134603;135130.64869351182;138459.30770346083;140024;142729;117969.41015294868;118858;119169;119825;124159.37920310341;104199.47900260451;107858.46070769752;108481;109026.45486772516;109227;9574.521273936136;9596.376235544667;9605.711828645151;9631.470269135265;9707.854382184132;59197.70401147967;60021.69989150026;61389.386106138365;61872.38127618846;64314.67842660757;87608.56195718981;92096.85564149496;92161.07838921525;92706.07293927245;92963;11400.201985860911;11531;11813.763724725286;11927.284362938251;12141.69645758863;12372.81440778371;12496.500139994661;12660.240385576895;12726.490940362652;13013;12594.055445841781;12635.557755478514;12671.556495522613;12834.16577922432;13035.804462932874;12397.256164630151;12668.429920653409;12743.171693839871;12828.616079180636;12996.935015324863;11266.155038371991;11451.713707157387;11746.70633234176;12020.79792020791;12217.877821221675;11039;11043.889561104286;11254.324740515594;11807.409629518661;11988.820167697666;11705.414729263332;11718;11927.76144477123;11971.76056478883;12001.63995080149 04:02:25 INFO - PROCESS | 3540 | __end_tp_report 04:02:25 INFO - PROCESS | 3540 | __start_cc_report 04:02:25 INFO - PROCESS | 3540 | _x_x_mozilla_cycle_collect,1099 04:02:25 INFO - PROCESS | 3540 | __end_cc_report 04:02:25 INFO - PROCESS | 3540 | __startTimestamp1473764545905__endTimestamp 04:02:25 INFO - PROCESS | 3540 | 04:02:25 INFO - PROCESS | 3540 | ------- Summary: start ------- 04:02:25 INFO - PROCESS | 3540 | Number of tests: 6 04:02:25 INFO - PROCESS | 3540 | 04:02:25 INFO - PROCESS | 3540 | [#0] dojo.html Cycles:165 Average:5616.79 Median:5874.27 stddev:639.01 (10.9%) stddev-sans-first:612.78 04:02:25 INFO - PROCESS | 3540 | Values: 3224.0 3240.0 3263.4 3281.8 3316.4 5586.5 5635.7 5663.6 5678.3 5704.6 5643.4 5685.9 5686.4 5690.1 5690.3 4900.4 4971.7 5130.6 5135.8 5183.1 5072.3 5114.0 5120.5 5122.3 5127.3 5045.1 5082.1 5084.9 5112.8 5119.3 5211.5 5238.5 5241.9 5245.7 5278.5 4877.7 5008.7 5013.9 5117.3 5122.1 5584.1 5611.4 5617.5 5642.6 5650.5 6149.5 6153.1 6153.4 6155.6 6158.7 5924.5 5967.0 5994.8 6041.7 6170.8 5588.9 5633.4 5640.3 5671.0 5677.6 5874.3 6167.3 6168.9 6170.8 6199.7 5830.2 5830.6 5846.1 5888.0 5925.5 5975.9 5977.2 5980.4 5982.9 5983.6 5795.5 5958.5 5964.7 5972.0 5973.9 6015.5 6045.2 6074.6 6086.6 6094.7 4637.2 4641.7 4695.7 4696.6 4720.4 4081.1 4176.7 4196.1 4221.1 4222.6 5645.2 5646.0 5661.8 5741.3 5773.1 5968.3 6012.5 6018.8 6024.5 6030.7 5570.5 5654.0 5655.2 5716.6 5721.3 5165.8 5202.2 5229.7 5272.2 5305.2 6291.2 6300.3 6303.1 6303.7 6305.5 5824.6 6061.2 6069.3 6077.3 6079.1 6178.5 6230.7 6244.3 6313.0 6350.9 6004.3 6005.3 6008.1 6019.1 6024.1 5778.9 6113.8 6125.8 6130.6 6132.5 5887.6 5940.2 5954.8 5994.1 6043.3 5910.1 5920.4 5941.8 5944.4 5947.0 5531.3 5879.3 5916.3 5919.5 5927.1 5950.3 5984.9 5995.2 6126.0 6129.0 5927.2 5943.3 5945.2 5945.7 5948.7 04:02:25 INFO - PROCESS | 3540 | 04:02:25 INFO - PROCESS | 3540 | [#1] ext.html Cycles:180 Average:63734.57 Median:196165.79 stddev:126042.59 (64.3%) stddev-sans-first:126307.06 04:02:25 INFO - PROCESS | 3540 | Values: 608.9 632.1 667.7 674.3 688.2 34315.5 44679.0 45908.0 45912.3 45936.0 47766.8 48230.8 48411.3 48429.3 48495.5 8737.0 9514.7 9655.3 9659.8 9659.9 8755.1 9065.2 9068.6 11194.5 11494.4 11785.4 11836.1 11838.3 11842.9 11856.5 11593.3 11620.8 11661.9 11674.5 11688.3 11403.5 11448.4 11453.7 11461.8 11563.9 46877.0 47284.8 47548.0 47742.3 47786.0 3584.6 3657.3 3680.6 3732.9 3968.6 35607.8 35621.6 35800.3 35939.3 36359.3 4786.4 4838.6 4845.3 4845.7 4966.5 386064.0 386773.0 387009.0 387830.0 388363.0 37501.8 38140.0 38533.8 67027.7 67420.7 41666.6 41720.4 42029.0 42091.2 42146.2 15462.1 15513.4 15547.1 15586.5 15594.6 11576.2 12133.8 12201.3 12202.1 12206.2 9072.2 9173.8 9275.6 9483.4 9484.3 3492.4 3503.3 3597.8 3614.2 3615.6 1069.4 1132.9 1149.5 1176.0 1194.9 35058.0 35384.3 35414.6 35470.5 35616.6 1173.2 1217.5 1227.7 1248.9 1267.6 17976.7 18335.4 18433.7 18618.3 18739.9 560117.0 566476.0 567313.0 568200.0 568605.0 66517.0 67055.0 67175.7 67190.7 67200.7 1321.8 1380.8 1412.9 1476.4 1490.8 30830.7 36283.6 36617.8 36925.4 36978.0 56792.7 57181.0 57328.1 57333.0 57396.4 453862.0 454432.0 455835.0 456147.0 457119.0 30783.0 31493.5 31587.2 32015.8 33571.5 53194.0 53823.0 53971.7 55150.7 55735.2 45243.8 46323.3 46438.8 47195.5 47493.8 45656.5 45674.0 46718.5 47273.8 47393.1 45369.0 46023.8 46679.5 46836.0 48523.8 38638.0 41201.2 41204.8 41445.6 42089.0 30439.7 30439.8 30681.8 30985.9 31013.4 04:02:25 INFO - PROCESS | 3540 | 04:02:25 INFO - PROCESS | 3540 | [#2] jquery.html Cycles:180 Average:31112.03 Median:2438.41 stddev:90449.58 (3709.4%) stddev-sans-first:90673.90 04:02:25 INFO - PROCESS | 3540 | Values: 391.6 413.3 429.4 434.8 435.7 15071.3 20189.8 20490.3 20505.1 20619.7 19878.2 19922.0 19981.6 19999.3 20203.4 5753.9 5822.6 6026.5 6067.7 6119.3 6163.4 6163.8 6173.9 6188.8 6202.0 6228.2 6529.6 6570.8 6578.7 6579.5 5765.1 5997.8 6569.2 6664.6 6695.3 5922.2 5936.0 5942.9 5948.6 5950.0 13396.7 21828.1 23640.5 23671.8 23854.6 2285.5 2293.4 2338.6 2403.0 2473.8 10511.6 10570.6 10621.8 10825.7 11181.2 2002.8 2059.1 2066.9 2075.0 2102.6 66758.6 71444.6 74612.3 88913.1 93259.1 11183.7 11361.4 11711.2 11956.5 12131.4 16454.3 16475.5 16477.3 16505.8 16509.3 7986.4 8036.8 8368.0 8370.2 8466.2 7524.8 7683.1 7705.3 7723.6 8018.1 4581.9 4591.3 5032.1 5045.1 5170.6 2058.0 2075.8 2103.2 2121.5 2123.5 970.5 970.6 980.1 982.5 1062.8 18404.4 20053.0 20219.3 20234.9 20387.2 1003.2 1003.4 1005.8 1008.5 1029.6 10230.3 10912.6 10927.3 11019.1 11082.7 532958.0 543178.0 549193.0 555514.0 565326.0 94926.5 98089.5 99117.0 100067.0 103151.5 987.8 1014.8 1033.3 1034.2 1045.8 17295.1 17419.1 17805.7 17843.8 17987.8 45486.8 45553.8 45610.3 46152.0 46263.8 61558.0 62185.4 62437.0 62664.4 63887.4 8797.3 8873.6 9074.3 9194.7 9254.3 12651.9 12822.7 13168.3 13267.7 13610.0 11589.9 11734.4 11765.5 11919.3 12116.5 10821.3 10837.5 10888.8 11671.1 12162.2 11243.4 11407.3 11667.4 11768.8 11966.0 8853.4 8914.2 8986.1 9056.6 9060.3 8687.0 8727.0 8855.7 8870.5 9335.7 04:02:25 INFO - PROCESS | 3540 | 04:02:25 INFO - PROCESS | 3540 | [#3] mootools.html Cycles:180 Average:6256.50 Median:4682.96 stddev:3700.06 (79.0%) stddev-sans-first:3692.53 04:02:25 INFO - PROCESS | 3540 | Values: 1412.0 1579.4 1603.6 1806.2 1916.2 9009.8 9442.8 9504.6 9810.4 9811.6 9617.4 9720.2 9726.1 9734.8 9739.8 2400.5 2434.9 2435.9 2436.8 2446.1 2421.4 2427.9 2471.0 2472.4 2474.9 2369.4 2372.7 2418.2 2450.0 2452.3 2361.3 2366.0 2371.8 2371.9 2424.8 2388.5 2413.5 2436.7 2440.8 2442.0 9637.0 9646.4 9743.2 9758.7 9776.0 5507.7 5620.5 5639.5 5641.2 5647.2 3650.1 3660.7 3682.7 3714.3 3725.1 1240.3 1265.2 1267.1 1279.3 1327.2 13874.7 13882.0 13917.4 13944.9 13955.1 4731.8 4788.3 4805.8 4842.8 4938.0 10700.5 10736.9 10740.4 10748.3 10787.8 5496.4 5498.4 5504.5 5507.4 5512.2 4601.5 4679.5 4686.4 5007.3 5070.1 4244.7 4261.2 4370.0 4446.4 4498.0 1273.7 1274.8 1299.5 1301.4 1305.1 1018.7 1020.0 1023.7 1030.1 1034.8 6169.9 6177.8 6254.3 6344.6 6472.4 470.4 472.1 474.8 476.7 477.2 4076.4 4086.8 4092.7 4122.4 4125.9 11805.5 12160.5 12251.9 12268.1 12274.6 12164.6 12169.2 12175.3 12178.9 12207.4 8514.0 8741.2 8773.4 8777.9 9034.8 6232.3 6393.2 6753.2 6901.3 6978.3 11137.9 11196.4 11382.9 11385.1 11529.8 9665.1 11020.0 11593.2 11625.8 11741.8 8267.1 8372.0 8691.7 8708.6 8710.6 9170.6 9454.5 9470.1 9488.1 9488.7 8190.2 8233.8 8250.5 8298.4 8325.3 8019.3 8180.7 8214.4 8218.6 8234.1 7063.1 7065.0 7141.6 7203.7 7220.4 5076.4 5139.2 5190.8 5212.4 5277.4 5601.8 5635.6 5722.5 5777.4 5801.2 04:02:25 INFO - PROCESS | 3540 | 04:02:25 INFO - PROCESS | 3540 | [#4] prototype.html Cycles:180 Average:4816.61 Median:5514.78 stddev:1403.39 (25.4%) stddev-sans-first:1366.57 04:02:25 INFO - PROCESS | 3540 | Values: 342.9 350.9 355.0 356.9 362.1 5577.9 5619.9 5621.2 5627.4 5640.6 5496.6 5532.9 5533.2 5546.9 5561.5 4263.4 4342.5 4365.4 4380.8 4404.4 4326.0 4334.8 4362.3 4368.6 4383.2 4052.1 4302.8 4307.4 4308.9 4343.4 4142.2 4324.4 4326.7 4352.0 4356.3 3865.6 3871.1 3873.2 3885.4 3887.8 5009.5 5324.5 5344.1 5371.3 5384.6 4498.6 4854.2 4887.2 4926.3 4941.5 5696.5 5702.5 5712.8 5728.5 5759.0 3945.2 4127.4 4265.2 4268.1 4271.1 5923.2 5935.5 5951.6 5969.7 5981.2 4266.6 4278.6 4300.9 4312.3 4315.2 4148.5 4350.8 4371.8 4374.8 4381.6 4923.6 4925.3 4944.4 4974.9 4997.4 5821.4 5885.7 5893.2 5923.9 5932.7 1430.6 1494.6 1513.1 1515.3 1529.9 951.5 965.7 982.8 993.9 994.4 4963.8 4982.2 4985.6 5006.1 5017.5 5118.6 5125.3 5155.9 5159.8 5168.4 4928.8 4940.3 4973.6 5184.5 5344.6 2750.5 3123.6 3134.1 3135.6 3147.7 6094.0 6109.3 6125.1 6135.3 6158.9 5828.4 5841.5 5944.9 5950.2 5976.4 5989.2 6052.7 6061.2 6078.2 6080.4 5858.6 5866.9 5879.6 5913.2 5918.1 5787.3 5814.9 5817.7 5820.2 5827.6 5844.2 6024.2 6048.5 6093.9 6095.6 4038.8 4090.8 4192.3 4272.6 4284.2 5860.8 5917.1 5917.4 5922.7 5934.5 5673.8 5719.6 5803.2 5831.7 5841.8 5754.2 5767.7 5785.0 5797.0 5823.0 5554.5 5725.5 5737.5 5741.9 5774.2 5691.9 5733.6 5756.0 5757.1 5772.2 5617.9 5642.5 5689.7 5692.5 5759.4 04:02:25 INFO - PROCESS | 3540 | 04:02:25 INFO - PROCESS | 3540 | [#5] yui.html Cycles:180 Average:23954.04 Median:3651.87 stddev:36697.51 (1004.9%) stddev-sans-first:36759.12 04:02:25 INFO - PROCESS | 3540 | Values: 755.9 776.0 812.0 820.4 873.3 16108.7 16742.2 17312.0 17342.4 17525.1 15175.0 15513.8 15540.5 15618.4 15622.2 724.6 858.6 870.4 879.2 880.8 878.3 887.2 888.9 889.0 890.4 937.7 938.6 939.2 940.3 943.2 750.3 897.9 928.9 929.5 930.4 683.0 684.1 686.5 686.6 686.9 15541.5 15549.7 15572.2 15584.0 15596.1 14384.9 14931.4 15210.9 15412.3 15751.2 14320.6 14379.7 14381.5 14641.7 14767.4 4555.8 4579.8 4598.7 4638.6 4686.7 87874.0 89124.1 92234.1 92873.5 93050.5 12036.5 12119.4 12238.8 12377.6 12457.3 23174.0 23235.4 23263.0 23289.0 23364.9 9217.2 9293.9 9331.3 9368.5 9769.1 5023.0 5115.5 5137.9 5231.8 5311.6 3541.9 3602.4 3643.9 3659.9 3682.2 625.8 636.2 638.6 649.6 651.3 661.8 709.5 718.1 724.2 724.9 9795.6 9947.2 10092.4 10201.6 10323.9 659.4 675.4 721.4 732.0 738.2 4549.8 4769.2 4799.6 4838.5 4851.1 134603.0 135130.6 138459.3 140024.0 142729.0 117969.4 118858.0 119169.0 119825.0 124159.4 104199.5 107858.5 108481.0 109026.5 109227.0 9574.5 9596.4 9605.7 9631.5 9707.9 59197.7 60021.7 61389.4 61872.4 64314.7 87608.6 92096.9 92161.1 92706.1 92963.0 11400.2 11531.0 11813.8 11927.3 12141.7 12372.8 12496.5 12660.2 12726.5 13013.0 12594.1 12635.6 12671.6 12834.2 13035.8 12397.3 12668.4 12743.2 12828.6 12996.9 11266.2 11451.7 11746.7 12020.8 12217.9 11039.0 11043.9 11254.3 11807.4 11988.8 11705.4 11718.0 11927.8 11971.8 12001.6 04:02:25 INFO - PROCESS | 3540 | -------- Summary: end -------- 04:02:25 INFO - PROCESS | 3540 | 04:02:26 INFO - TEST-INFO | 3540: exit 0 04:02:26 INFO - TEST-OK | dromaeo_css | took 1112966ms 04:02:26 INFO - TEST-START | kraken 04:02:26 INFO - operating with platform_type : linux_ 04:02:26 INFO - Initialising browser for kraken test... 04:02:26 INFO - TEST-INFO | started process 3744 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpClfGMr/profile http://localhost:42886/getInfo.html) 04:02:30 INFO - PROCESS | 3744 | __metrics Screen width/height:1600/1200 04:02:30 INFO - PROCESS | 3744 | colorDepth:24 04:02:30 INFO - PROCESS | 3744 | Browser inner width/height: 1024/697 04:02:30 INFO - PROCESS | 3744 | __metrics 04:02:31 INFO - PROCESS | 3744 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:02:31 INFO - PROCESS | 3744 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:02:32 INFO - TEST-INFO | 3744: exit 0 04:02:32 INFO - Browser initialized. 04:02:32 INFO - Running cycle 1/1 for kraken test... 04:02:32 INFO - TEST-INFO | started process 3835 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpClfGMr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:02:44 INFO - PROCESS | 3835 | RSS: Main: 145805312 04:02:44 INFO - PROCESS | 3835 | 04:02:46 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42886/tests/kraken/driver.html?testName=audio-beat-detection) 04:02:47 INFO - PROCESS | 3835 | RSS: Main: 200253440 04:02:47 INFO - PROCESS | 3835 | 04:02:49 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42886/tests/kraken/driver.html?testName=audio-dft) 04:02:50 INFO - PROCESS | 3835 | RSS: Main: 185004032 04:02:50 INFO - PROCESS | 3835 | 04:02:53 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42886/tests/kraken/driver.html?testName=audio-fft) 04:02:53 INFO - PROCESS | 3835 | RSS: Main: 275820544 04:02:53 INFO - PROCESS | 3835 | 04:02:56 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42886/tests/kraken/driver.html?testName=audio-oscillator) 04:02:56 INFO - PROCESS | 3835 | RSS: Main: 181252096 04:02:56 INFO - PROCESS | 3835 | 04:02:58 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42886/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:02:59 INFO - PROCESS | 3835 | RSS: Main: 180736000 04:02:59 INFO - PROCESS | 3835 | 04:03:02 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42886/tests/kraken/driver.html?testName=imaging-darkroom) 04:03:02 INFO - PROCESS | 3835 | RSS: Main: 216625152 04:03:02 INFO - PROCESS | 3835 | 04:03:06 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42886/tests/kraken/driver.html?testName=imaging-desaturate) 04:03:06 INFO - PROCESS | 3835 | RSS: Main: 214790144 04:03:06 INFO - PROCESS | 3835 | 04:03:09 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42886/tests/kraken/driver.html?testName=json-parse-financial) 04:03:10 INFO - PROCESS | 3835 | RSS: Main: 215543808 04:03:10 INFO - PROCESS | 3835 | 04:03:11 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42886/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:03:12 INFO - PROCESS | 3835 | RSS: Main: 177815552 04:03:12 INFO - PROCESS | 3835 | 04:03:14 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42886/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:03:14 INFO - PROCESS | 3835 | RSS: Main: 184385536 04:03:14 INFO - PROCESS | 3835 | 04:03:16 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42886/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:03:17 INFO - PROCESS | 3835 | RSS: Main: 185843712 04:03:17 INFO - PROCESS | 3835 | 04:03:19 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42886/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:03:20 INFO - PROCESS | 3835 | RSS: Main: 186855424 04:03:20 INFO - PROCESS | 3835 | 04:03:23 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42886/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:03:23 INFO - PROCESS | 3835 | RSS: Main: 184844288 04:03:23 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | Cycle 1(1): loaded http://localhost:42886/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42886/tests/kraken/driver.html?testName=ai-astar) 04:03:25 INFO - PROCESS | 3835 | RSS: Main: 235573248 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | __start_tp_report 04:03:25 INFO - PROCESS | 3835 | _x_x_mozilla_page_load 04:03:25 INFO - PROCESS | 3835 | _x_x_mozilla_page_load_details 04:03:25 INFO - PROCESS | 3835 | |i|pagename|runs| 04:03:25 INFO - PROCESS | 3835 | |0;ai-astar;110;110;118;112;111;109;110;110;110;111 04:03:25 INFO - PROCESS | 3835 | |1;audio-beat-detection;146;117;144;177;116;139;146;119;177;117 04:03:25 INFO - PROCESS | 3835 | |2;audio-dft;162;161;159;160;158;160;161;161;161;159 04:03:25 INFO - PROCESS | 3835 | |3;audio-fft;75;73;75;167;75;75;74;75;169;74 04:03:25 INFO - PROCESS | 3835 | |4;audio-oscillator;84;83;154;84;84;84;135;84;84;148 04:03:25 INFO - PROCESS | 3835 | |5;imaging-gaussian-blur;116;116;116;117;116;117;116;117;117;118 04:03:25 INFO - PROCESS | 3835 | |6;imaging-darkroom;146;146;146;146;146;145;147;146;145;146 04:03:25 INFO - PROCESS | 3835 | |7;imaging-desaturate;95;95;96;94;95;94;95;95;94;95 04:03:25 INFO - PROCESS | 3835 | |8;json-parse-financial;50;49;52;50;49;50;52;50;49;49 04:03:25 INFO - PROCESS | 3835 | |9;json-stringify-tinderbox;59;61;59;59;59;59;59;59;59;59 04:03:25 INFO - PROCESS | 3835 | |10;stanford-crypto-aes;76;73;82;72;78;72;71;71;72;72 04:03:25 INFO - PROCESS | 3835 | |11;stanford-crypto-ccm;120;118;116;117;116;116;117;116;154;118 04:03:25 INFO - PROCESS | 3835 | |12;stanford-crypto-pbkdf2;154;156;158;154;153;153;158;154;154;153 04:03:25 INFO - PROCESS | 3835 | |13;stanford-crypto-sha256-iterative;63;66;66;62;64;65;64;63;64;60 04:03:25 INFO - PROCESS | 3835 | __end_tp_report 04:03:25 INFO - PROCESS | 3835 | __start_cc_report 04:03:25 INFO - PROCESS | 3835 | _x_x_mozilla_cycle_collect,1709 04:03:25 INFO - PROCESS | 3835 | __end_cc_report 04:03:25 INFO - PROCESS | 3835 | __startTimestamp1473764605559__endTimestamp 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | ------- Summary: start ------- 04:03:25 INFO - PROCESS | 3835 | Number of tests: 14 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#0] ai-astar Cycles:10 Average:111.10 Median:110.50 stddev:2.56 (2.3%) stddev-sans-first:2.68 04:03:25 INFO - PROCESS | 3835 | Values: 110.0 110.0 118.0 112.0 111.0 109.0 110.0 110.0 110.0 111.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:145.00 stddev:23.34 (16.1%) stddev-sans-first:24.64 04:03:25 INFO - PROCESS | 3835 | Values: 146.0 117.0 144.0 177.0 116.0 139.0 146.0 119.0 177.0 117.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#2] audio-dft Cycles:10 Average:160.20 Median:161.00 stddev:1.23 (0.8%) stddev-sans-first:1.12 04:03:25 INFO - PROCESS | 3835 | Values: 162.0 161.0 159.0 160.0 158.0 160.0 161.0 161.0 161.0 159.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#3] audio-fft Cycles:10 Average:93.20 Median:75.00 stddev:39.43 (52.6%) stddev-sans-first:41.27 04:03:25 INFO - PROCESS | 3835 | Values: 75.0 73.0 75.0 167.0 75.0 75.0 74.0 75.0 169.0 74.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:84.00 stddev:30.21 (36.0%) stddev-sans-first:31.30 04:03:25 INFO - PROCESS | 3835 | Values: 84.0 83.0 154.0 84.0 84.0 84.0 135.0 84.0 84.0 148.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 04:03:25 INFO - PROCESS | 3835 | Values: 116.0 116.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 118.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 04:03:25 INFO - PROCESS | 3835 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 147.0 146.0 145.0 146.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 04:03:25 INFO - PROCESS | 3835 | Values: 95.0 95.0 96.0 94.0 95.0 94.0 95.0 95.0 94.0 95.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:1.15 (2.3%) stddev-sans-first:1.22 04:03:25 INFO - PROCESS | 3835 | Values: 50.0 49.0 52.0 50.0 49.0 50.0 52.0 50.0 49.0 49.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 04:03:25 INFO - PROCESS | 3835 | Values: 59.0 61.0 59.0 59.0 59.0 59.0 59.0 59.0 59.0 59.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:72.50 stddev:3.63 (5.0%) stddev-sans-first:3.77 04:03:25 INFO - PROCESS | 3835 | Values: 76.0 73.0 82.0 72.0 78.0 72.0 71.0 71.0 72.0 72.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#11] stanford-crypto-ccm Cycles:10 Average:120.80 Median:117.50 stddev:11.74 (10.0%) stddev-sans-first:12.44 04:03:25 INFO - PROCESS | 3835 | Values: 120.0 118.0 116.0 117.0 116.0 116.0 117.0 116.0 154.0 118.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.70 Median:154.00 stddev:1.95 (1.3%) stddev-sans-first:2.05 04:03:25 INFO - PROCESS | 3835 | Values: 154.0 156.0 158.0 154.0 153.0 153.0 158.0 154.0 154.0 153.0 04:03:25 INFO - PROCESS | 3835 | 04:03:25 INFO - PROCESS | 3835 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:1.83 (2.9%) stddev-sans-first:1.92 04:03:25 INFO - PROCESS | 3835 | Values: 63.0 66.0 66.0 62.0 64.0 65.0 64.0 63.0 64.0 60.0 04:03:25 INFO - PROCESS | 3835 | -------- Summary: end -------- 04:03:25 INFO - PROCESS | 3835 | 04:03:26 INFO - TEST-INFO | 3835: exit 0 04:03:26 INFO - TEST-OK | kraken | took 59963ms 04:03:26 INFO - SUITE-END | took 1172s 04:03:26 INFO - Completed test suite (00:19:33) 04:03:26 INFO - javascript benchmark 04:03:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3224.016674914151, 3240.0441869648457, 3263.4288999425094, 3281.753868459866, 3316.3698897209542, 5586.525145362644, 5635.718214089295, 5663.603547751659, 5678.318601767794, 5704.629199102061, 5643.379228284885, 5685.857853553653, 5686.402927692595, 5690.146478028298, 5690.317161940573, 4900.411950565937, 4971.676841005341, 5130.589552835783, 5135.82024629139, 5183.144781111112, 5072.315237442954, 5114.0, 5120.4623514530995, 5122.308488354061, 5127.3078134451935, 5045.091883460976, 5082.0598189334905, 5084.898302033957, 5112.821051263207, 5119.334486516747, 5211.4788521147775, 5238.528532432099, 5241.9213711794355, 5245.711485868275, 5278.5249327560705, 4877.731724755137, 5008.724520151391, 5013.89972200554, 5117.309163262949, 5122.129238029506, 5584.078627026536, 5611.3546942101775, 5617.522510586564, 5642.633228840154, 5650.5479561634975, 6149.538784591129, 6153.107799369066, 6153.446189842891, 6155.569110162291, 6158.692065396713, 5924.496417804448, 5967.015442451991, 5994.760209591611, 6041.72812223451, 6170.81487555374, 5588.944110558924, 5633.436656334375, 5640.32316122068, 5671.0, 5677.602567820296, 5874.265716785402, 6167.321594624587, 6168.8766224675255, 6170.81487555365, 6199.721012554446, 5830.154627578977, 5830.62100963436, 5846.123081537803, 5888.028475301613, 5925.466707996301, 5975.880482390415, 5977.163197152402, 5980.401959804072, 5982.940170598238, 5983.551233657406, 5795.507381872504, 5958.493528050164, 5964.671943043174, 5971.970140149271, 5973.850653733692, 6015.488683461867, 6045.244344456943, 6074.605150665193, 6086.604370715978, 6094.725737341831, 4637.234856248749, 4641.721496710208, 4695.694779839299, 4696.577308042292, 4720.386349774576, 4081.10215752534, 4176.686748493875, 4196.097838964637, 4221.050263690645, 4222.59885310895, 5645.181448689917, 5646.040173170571, 5661.7735290588735, 5741.339745929272, 5773.1051686988585, 5968.313643930916, 6012.458878700851, 6018.849528761816, 6024.518038556905, 6030.698465076816, 5570.498655121059, 5653.971730141323, 5655.208270842086, 5716.57125715573, 5721.342045664718, 5165.767540460688, 5202.193659982709, 5229.686218826882, 5272.2355258487305, 5305.1776974569, 6291.150694656163, 6300.2754683212015, 6303.117563541108, 6303.747850086037, 6305.464035556937, 5824.5631577631, 6061.212042434456, 6069.302030266399, 6077.331493535619, 6079.148919151234, 6178.505719542336, 6230.719617617127, 6244.344343844013, 6313.0, 6350.872982540416, 6004.309504407049, 6005.279366476051, 6008.098785182083, 6019.067044608093, 6024.096385542204, 5778.94221057784, 6113.786017489367, 6125.84685382869, 6130.601510901777, 6132.509399247961, 5887.587868849139, 5940.1683764272175, 5954.791582294599, 5994.100884867305, 6043.2748070231855, 5910.143029260819, 5920.407959204045, 5941.792037278675, 5944.375840536854, 5947.0, 5531.280933478622, 5879.265091863517, 5916.349201587908, 5919.4968427684125, 5927.140564618193, 5950.34546199909, 5984.898569887665, 5995.190649262294, 6126.019836826086, 6129.049997250433, 5927.229460170151, 5943.316518600243, 5945.19739835134, 5945.732442040032, 5948.702564871653], "value": 5573.7735831894015, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.9360617135201, 632.1024145713087, 667.7128834601123, 674.3492529708833, 688.2257460357098, 34315.48526772097, 44679.0, 45908.0, 45912.3113153303, 45936.0, 47766.76116619421, 48230.75884620572, 48411.27383089256, 48429.27356089662, 48495.51504484954, 8737.0, 9514.714558563253, 9655.27585431092, 9659.80680386392, 9659.855102173438, 8755.080716524768, 9065.18413342799, 9068.637254509844, 11194.496247668874, 11494.36780977046, 11785.410729463492, 11836.053115750763, 11838.289702617827, 11842.940785296061, 11856.466459009322, 11593.304401735923, 11620.767584648343, 11661.941690291536, 11674.474648640746, 11688.298702077902, 11403.486843092081, 11448.370339631318, 11453.713657158638, 11461.828072578917, 11563.88436115645, 46877.0, 47284.7635761819, 47548.049039019024, 47742.28386574204, 47786.0, 3584.5877724061806, 3657.3416784978695, 3680.6319368063246, 3732.869349572779, 3968.583298753647, 35607.82196089003, 35621.64378356209, 35800.31497637661, 35939.28121437583, 36359.27281454356, 4786.44955830077, 4838.612910967114, 4845.297431872359, 4845.685030473008, 4966.453690094087, 386063.9999999972, 386773.0, 387009.0, 387830.0, 388363.0, 37501.81249093792, 38140.0, 38533.807330963165, 67027.66486167636, 67420.6628966852, 41666.58333416688, 41720.37419438711, 42029.0, 42091.15817683691, 42146.15707685829, 15462.14958177311, 15513.379464821506, 15547.067175969478, 15586.454474093352, 15594.610134746496, 11576.24754390962, 12133.817992730115, 12201.328926909106, 12202.084843636763, 12206.206596571194, 9072.228860546928, 9173.807405037302, 9275.58259878307, 9483.430994140374, 9484.336096473318, 3492.406290930548, 3503.3168532136497, 3597.8021208833766, 3614.168741189541, 3615.638436156416, 1069.3904474449644, 1132.8923752243525, 1149.51720277482, 1175.9529618815152, 1194.9066604057364, 35058.0, 35384.29231415409, 35414.64585354165, 35470.467942980875, 35616.64383356185, 1173.2491205628405, 1217.5129948020863, 1227.711487800384, 1248.875112488762, 1267.5880338889897, 17976.678473355325, 18335.35826246075, 18433.723494147598, 18618.255269789326, 18739.90630046841, 560117.0, 566476.0, 567313.0, 568200.0, 568605.0, 66517.0, 67055.0, 67175.66412167907, 67190.66404667945, 67200.66399668068, 1321.8017297405465, 1380.7790753479596, 1412.894032947533, 1476.438953197778, 1490.8434614365553, 30830.691693082783, 36283.637163628024, 36617.81691091634, 36925.446118308784, 36978.0, 56792.71603641955, 57180.999999998334, 57328.1400778997, 57333.0, 57396.42603574078, 453862.0, 454432.0, 455835.0, 456147.0, 457119.0, 30783.0, 31493.527597085606, 31587.21031974219, 32015.83992080025, 33571.49642755312, 53194.0, 53823.0, 53971.730141350614, 55150.72424637851, 55735.16397253963, 45243.77378113088, 46323.30515042209, 46438.767806162105, 47195.52804471911, 47493.762531188506, 45656.54343456523, 45674.0, 46718.53281467278, 47273.763631183, 47393.05213895772, 45369.0, 46023.76988115038, 46679.53320466752, 46836.0, 48523.75738121287, 38638.0, 41201.1759764809, 41204.79397602993, 41445.585544144174, 42089.0, 30439.695603043685, 30439.847800760854, 30681.846590767796, 30985.929424565216, 31013.379732405676], "value": 20864.668447513464, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.5692737988215, 413.2870797873666, 429.3795465552277, 434.8282428440766, 435.65365534400166, 15071.321790519425, 20189.798102018976, 20490.28284010058, 20505.07727152278, 20619.69070463944, 19878.20487180515, 19922.0, 19981.60036799267, 19999.300024499145, 20203.39389818304, 5753.942460575404, 5822.592418530715, 6026.5178785697035, 6067.726952287136, 6119.326874043852, 6163.414475624809, 6163.845903852395, 6173.876522469548, 6188.752449901998, 6202.0, 6228.19033525641, 6529.640869752162, 6570.770023049195, 6578.671066446683, 6579.506537009719, 5765.106408506646, 5997.790077347272, 6569.178852643419, 6664.633445160514, 6695.2969938156275, 5922.170896074553, 5935.970320148414, 5942.910856337158, 5948.613340132921, 5950.0, 13396.665083372896, 21828.126874924983, 23640.527189456116, 23671.763282367127, 23854.642180367307, 2285.5086156476273, 2293.4037150341032, 2338.5673650374733, 2402.9879850600637, 2473.839200451983, 10511.632092876713, 10570.630027948984, 10621.787564248749, 10825.729356766065, 11181.161412893984, 2002.819746222847, 2059.1145807302855, 2066.917323307081, 2075.0351086744586, 2102.568973360457, 66758.56597411622, 71444.64277678578, 74612.25387746107, 88913.11086889113, 93259.06740932571, 11183.664490065312, 11361.431928403546, 11711.238769479956, 11956.521739130338, 12131.393430328448, 16454.341826326814, 16475.505734827973, 16477.34090636361, 16505.83494165043, 16509.257083431276, 7986.361091112697, 8036.839263214703, 8368.0, 8370.246677798905, 8466.238038576557, 7524.774256772305, 7683.116441609175, 7705.267999540147, 7723.575203363756, 8018.118007019223, 4581.862544123682, 4591.288350305708, 5032.094223039815, 5045.142325804622, 5170.638055336166, 2057.98971005144, 2075.802798734118, 2103.1902717453586, 2121.5332626822074, 2123.50097727034, 970.4807927758616, 970.6069042037985, 980.1374790184734, 982.5038355630361, 1062.824633935392, 18404.447866564024, 20053.0, 20219.29232476856, 20234.898825505777, 20387.184512619624, 1003.1673710820002, 1003.4481035430484, 1005.8189525885265, 1008.5159123620704, 1029.6190409548365, 10230.28388012832, 10912.56349746017, 10927.344359338465, 11019.063379612822, 11082.722931926604, 532958.0, 543178.0, 549193.0, 555514.0, 565326.0, 94926.52536737273, 98089.50955245178, 99117.0, 100067.0, 103151.48424257981, 987.7728122531714, 1014.8071866345371, 1033.256055639938, 1034.1726618705006, 1045.8065257927155, 17295.13524323804, 17419.074321184085, 17805.732914006043, 17843.821561784214, 17987.820121798613, 45486.772566136955, 45553.77223113863, 45610.315845261684, 46152.0, 46263.768681156376, 61558.0, 62185.37814621796, 62437.0, 62664.37335626768, 63887.36112638814, 8797.296216302553, 8873.645054198018, 9074.274058075207, 9194.678186263449, 9254.259659227111, 12651.93674031624, 12822.743545128858, 13168.275744834125, 13267.734645306848, 13610.0, 11589.942050289694, 11734.41327933617, 11765.529378825093, 11919.3444360561, 12116.515339386679, 10821.296615719953, 10837.512311946139, 10888.836667450152, 11671.066314694974, 12162.209456385306, 11243.4378281084, 11407.315561066363, 11667.416629168338, 11768.764624707286, 11966.0, 8853.380263381501, 8914.153155450222, 8986.101389860962, 9056.637734490547, 9060.320475964198, 8687.0, 8727.0, 8855.73432797017, 8870.467771933705, 9335.719928402159], "value": 9477.770907889904, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.9647008824784, 1579.4392990488377, 1603.607116256517, 1806.1872157529115, 1916.1568909679731, 9009.819803603925, 9442.763930901723, 9504.572294246758, 9810.362326448776, 9811.607535698582, 9617.42295462271, 9720.222382209424, 9726.124648781626, 9734.805303893918, 9739.756506087333, 2400.543896659638, 2434.866082365469, 2435.914742983996, 2436.8050555955483, 2446.0582675669816, 2421.3583400398907, 2427.9393015174674, 2470.962935555968, 2472.4066224106264, 2474.8515089094712, 2369.4194922244073, 2372.7271363793125, 2418.238254949685, 2450.019992003195, 2452.3256104571296, 2361.3388251289666, 2366.0417530900013, 2371.7903869026836, 2371.905123795046, 2424.793892519138, 2388.498415332765, 2413.4931664350506, 2436.671049408325, 2440.8169387295848, 2442.0, 9637.0, 9646.372985755903, 9743.171830394425, 9758.707238782848, 9775.951120244425, 5507.669539827623, 5620.522255608278, 5639.464250896158, 5641.18202860587, 5647.152927060933, 3650.0692323457615, 3660.6705396514176, 3682.705383569308, 3714.312852122366, 3725.068732816796, 1240.3488168711408, 1265.1839563481503, 1267.0687045021903, 1279.2708156350884, 1327.1970457872944, 13874.65313367174, 13882.0, 13917.443302267897, 13944.860551394559, 13955.092918960134, 4731.848636594417, 4788.329633851264, 4805.7597120143855, 4842.806287748451, 4938.0, 10700.464976751131, 10736.946315268373, 10740.355578665305, 10748.301360411548, 10787.83818242727, 5496.422875598085, 5498.395176530578, 5504.504594586427, 5507.449255074461, 5512.228288039678, 4601.470830854495, 4679.508651591602, 4686.414198225222, 5007.298978143063, 5070.138076527, 4244.681648876347, 4261.232978063978, 4369.978150109229, 4446.377507149002, 4497.955020449819, 1273.7388835288743, 1274.8278982337265, 1299.493197652916, 1301.3688361144837, 1305.0864394923592, 1018.6638409324905, 1019.9898001020043, 1023.7184774187158, 1030.1346868630237, 1034.7671773850823, 6169.907451388232, 6177.752889884354, 6254.312025677172, 6344.555881088372, 6472.385123413268, 470.4495739984227, 472.12892213865365, 474.77922765914013, 476.69729721627016, 477.1959248666009, 4076.4089207065404, 4086.7547947123267, 4092.6930480214105, 4122.361034039729, 4125.876223713293, 11805.468753906094, 12160.452779624939, 12251.877481225252, 12268.141230113806, 12274.570390036306, 12164.574239901562, 12169.20900141488, 12175.269483830998, 12178.878211217952, 12207.389630518439, 8514.0, 8741.169588889044, 8773.385862989657, 8777.868331975025, 9034.774130646787, 6232.345603711727, 6393.200849893763, 6753.15585551806, 6901.344372284625, 6978.337057979484, 11137.944310278397, 11196.384198868815, 11382.943085284853, 11385.146114041314, 11529.82705259405, 9665.130138287588, 11020.0, 11593.24643898144, 11625.825612615987, 11741.76516469683, 8267.131951145044, 8372.037215720271, 8691.739247822576, 8708.60811263482, 8710.564471776512, 9170.633174672937, 9454.480003599869, 9470.14768670823, 9488.051194880456, 9488.715338539854, 8190.221928916743, 8233.79415514617, 8250.46371985819, 8298.377621678277, 8325.25072743456, 8019.318357939407, 8180.67277308901, 8214.424990250625, 8218.589070546568, 8234.05308389543, 7063.117110361205, 7064.964675176591, 7141.607211603412, 7203.71185152588, 7220.3862671671395, 5076.36545431821, 5139.152039913372, 5190.844274671766, 5212.426633070283, 5277.445868183785, 5601.80393686219, 5635.577331700057, 5722.542196624343, 5777.364489906023, 5801.1878337032], "value": 4866.994147443414, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.8817058114951, 350.89297764181924, 355.0343066858145, 356.90185199070254, 362.14894996757624, 5577.916331255029, 5619.943800561999, 5621.184928185411, 5627.43725627437, 5640.633358831673, 5496.615236933416, 5532.944670553294, 5533.197686335479, 5546.86132846679, 5561.4716601922855, 4263.4031235627035, 4342.54403287655, 4365.4106695596165, 4380.824767009316, 4404.427424434819, 4325.956740432587, 4334.761588112652, 4362.25841606926, 4368.628666563346, 4383.232934236512, 4052.1490486997764, 4302.763348015857, 4307.353896915456, 4308.913821723564, 4343.370211319357, 4142.150859073898, 4324.437823083011, 4326.653867690608, 4351.97824010878, 4356.324769660708, 3865.632764887331, 3871.1483473635753, 3873.2447172801317, 3885.3977633466857, 3887.844486220548, 5009.524095210949, 5324.547413469821, 5344.118220493614, 5371.274877891474, 5384.59615528833, 4498.617617502517, 4854.199057155565, 4887.242477416006, 4926.339776520675, 4941.481144479814, 5696.458836410576, 5702.45826646468, 5712.8000519982015, 5728.484436400702, 5759.0, 3945.230680017398, 4127.442795222637, 4265.189613973335, 4268.146370725867, 4271.103068355649, 5923.17075609415, 5935.465808077251, 5951.58338916276, 5969.671668058255, 5981.222441082631, 4266.573342665771, 4278.636315913182, 4300.935485967713, 4312.331588603772, 4315.244832154361, 4148.543660197376, 4350.760708161019, 4371.803268852909, 4374.759388233614, 4381.627561657231, 4923.556879880828, 4925.285833554115, 4944.431390390151, 4974.90050198994, 4997.400311962515, 5821.388754180834, 5885.705714714247, 5893.174955506232, 5923.881522369528, 5932.673702946379, 1430.5551393092842, 1494.5591050640087, 1513.137511618367, 1515.2575238133236, 1529.9388024478897, 951.5004622573205, 965.6909788867495, 982.7985263021063, 993.8956409577034, 994.4132961552718, 4963.801447942042, 4982.202847544376, 4985.601151907838, 5006.148954677641, 5017.494751574586, 5118.59051275897, 5125.333706618116, 5155.948440515547, 5159.7678104485385, 5168.3797944246935, 4928.778204980785, 4940.258961155855, 4973.602111831045, 5184.507471790174, 5344.625876188629, 2750.518659234626, 3123.6251649801716, 3134.0754477429214, 3135.5923729914966, 3147.716705496516, 6093.96953015241, 6109.266887973472, 6125.081237814275, 6135.32511423743, 6158.876822463615, 5828.424686620982, 5841.532677385799, 5944.910826337608, 5950.196723442366, 5976.372480889444, 5989.173302142888, 6052.6671033092725, 6061.151438798572, 6078.237311179797, 6080.391960803972, 5858.648481091148, 5866.911996319973, 5879.647221166572, 5913.172155898092, 5918.141869428822, 5787.276590426197, 5814.94185058144, 5817.70911454434, 5820.185174075548, 5827.621204621686, 5844.211031510862, 6024.246969128859, 6048.516118710405, 6093.9085913712215, 6095.573309868444, 4038.7980600970423, 4090.795460227036, 4192.329227323614, 4272.6154646082, 4284.207421627009, 5860.824175274749, 5917.112433135064, 5917.378675239038, 5922.703864806656, 5934.495567876606, 5673.8297851064535, 5719.570107473131, 5803.216565763568, 5831.679257640871, 5841.824745257649, 5754.223179870832, 5767.740451679769, 5784.971075144766, 5796.971015144897, 5822.970885145547, 5554.527865131509, 5725.484706376279, 5737.48362647347, 5741.91387129185, 5774.191613174243, 5691.8861622768145, 5733.59864809476, 5755.971220143872, 5757.0788673811985, 5772.1630363596205, 5617.915731263953, 5642.463965923231, 5689.715514224355, 5692.459216374438, 5759.366469688414], "value": 4373.852603580589, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.9357454616357, 775.9806004849878, 811.9756407307783, 820.3888103362996, 873.3493547307255, 16108.677826443463, 16742.24659890305, 17312.04783736894, 17342.393016244434, 17525.123743812805, 15175.0, 15513.84486155138, 15540.533783986499, 15618.375264989416, 15622.218889055559, 724.6087112958995, 858.5921687198562, 870.3602851903846, 879.1647934462254, 880.7842078690729, 878.3236907581158, 887.1704955866253, 888.8577827547593, 888.9911100888972, 890.3900827932854, 937.653068364706, 938.629241449627, 939.1876027236425, 940.2571968145157, 943.1888575824785, 750.3471979377922, 897.9461232326081, 928.8513837785955, 929.4748467116058, 930.3812964378681, 683.0369179456942, 684.1414025398094, 686.5297271369078, 686.5640318397803, 686.8523267497462, 15541.533753987398, 15549.689006219925, 15572.221388930508, 15583.987040842308, 15596.064236145867, 14384.856151438456, 14931.402743890232, 15210.923945380313, 15412.306446209948, 15751.2124393781, 14320.64198395038, 14379.71240575193, 14381.496647617283, 14641.70716585673, 14767.409303627843, 4555.840545580922, 4579.839705610321, 4598.655100867415, 4638.582527572501, 4686.695364801277, 87874.0, 89124.10875891223, 92234.07765922321, 92873.53563232208, 93050.53474732583, 12036.45835937385, 12119.39403029845, 12238.81641775374, 12377.566785162477, 12457.252564846138, 23174.0, 23235.419114522272, 23263.0, 23289.0, 23364.883175584015, 9217.170454659114, 9293.860592091123, 9331.253499720005, 9368.531573421302, 9769.071938165864, 5023.0, 5115.488451154929, 5137.897242055138, 5231.764570594332, 5311.628186026941, 3541.858325666996, 3602.3875941090078, 3643.872464463731, 3659.87190448333, 3682.2451397463456, 625.8122563231012, 636.2333388267127, 638.5753473939778, 649.6297110646885, 651.2933467188059, 661.7683810666229, 709.4501761135058, 718.1418205244682, 724.1780579042825, 724.8622761675265, 9795.559199835881, 9947.204223662235, 10092.44491552957, 10201.642942496977, 10323.896761032294, 659.3867703036223, 675.4326365852609, 721.3579913876654, 731.989020164698, 738.1990540263766, 4549.8307709104865, 4769.189237829577, 4799.616030717534, 4838.516148385203, 4851.100646912877, 134603.0, 135130.64869351182, 138459.30770346083, 140024.0, 142729.0, 117969.41015294868, 118858.0, 119169.0, 119825.0, 124159.37920310341, 104199.47900260451, 107858.46070769752, 108481.0, 109026.45486772516, 109227.0, 9574.521273936136, 9596.376235544667, 9605.711828645151, 9631.470269135265, 9707.854382184132, 59197.70401147967, 60021.69989150026, 61389.386106138365, 61872.38127618846, 64314.67842660757, 87608.56195718981, 92096.85564149496, 92161.07838921525, 92706.07293927245, 92963.0, 11400.201985860911, 11531.0, 11813.763724725286, 11927.284362938251, 12141.69645758863, 12372.81440778371, 12496.500139994661, 12660.240385576895, 12726.490940362652, 13013.0, 12594.055445841781, 12635.557755478514, 12671.556495522613, 12834.16577922432, 13035.804462932874, 12397.256164630151, 12668.429920653409, 12743.171693839871, 12828.616079180636, 12996.935015324863, 11266.155038371991, 11451.713707157387, 11746.70633234176, 12020.79792020791, 12217.877821221675, 11039.0, 11043.889561104286, 11254.324740515594, 11807.409629518661, 11988.820167697666, 11705.414729263332, 11718.0, 11927.76144477123, 11971.76056478883, 12001.63995080149], "value": 8032.460750681155, "unit": "score"}], "name": "dromaeo_css", "value": 7572.058040009576, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 118.0, 112.0, 111.0, 109.0, 110.0, 110.0, 110.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 144.0, 177.0, 116.0, 139.0, 146.0, 119.0, 177.0, 117.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 159.0, 160.0, 158.0, 160.0, 161.0, 161.0, 161.0, 159.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 75.0, 167.0, 75.0, 75.0, 74.0, 75.0, 169.0, 74.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 154.0, 84.0, 84.0, 84.0, 135.0, 84.0, 84.0, 148.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 118.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0, 146.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 52.0, 50.0, 49.0, 50.0, 52.0, 50.0, 49.0, 49.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 82.0, 72.0, 78.0, 72.0, 71.0, 71.0, 72.0, 72.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 118.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 154.0, 118.0], "value": 120.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 156.0, 158.0, 154.0, 153.0, 153.0, 158.0, 154.0, 154.0, 153.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 66.0, 62.0, 64.0, 65.0, 64.0, 63.0, 64.0, 60.0], "value": 63.7, "unit": "score"}], "name": "kraken", "value": 1486.3000000000002, "alertThreshold": 2.0}]} 04:03:26 INFO - Return code: 0 04:03:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:03:26 INFO - # TBPL SUCCESS # 04:03:26 INFO - Running post-action listener: _resource_record_post_action 04:03:26 INFO - [mozharness: 2016-09-13 11:03:26.876457Z] Finished run-tests step (success) 04:03:26 INFO - Running post-run listener: _resource_record_post_run 04:03:26 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 329732096; Read time: 28; Write time: 238296 04:03:26 INFO - TinderboxPrint: CPU usage
12.9% 04:03:26 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 04:03:26 INFO - TinderboxPrint: I/O write bytes / time
329,732,096 / 238,296 04:03:26 INFO - TinderboxPrint: CPU idle
8,268.2 (87.0%) 04:03:26 INFO - TinderboxPrint: CPU user
1,185.0 (12.5%) 04:03:26 INFO - TinderboxPrint: Swap in / out
0 / 0 04:03:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78364672; Read time: 0; Write time: 51628 04:03:26 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 266240; Write bytes: 251076608; Read time: 28; Write time: 186640 04:03:27 INFO - Running post-run listener: _upload_blobber_files 04:03:27 INFO - Blob upload gear active. 04:03:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:03:27 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 04:03:27 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'] 04:03:27 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 04:03:27 INFO - (blobuploader) - INFO - Open directory for files ... 04:03:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:03:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:03:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:28 INFO - (blobuploader) - INFO - Done attempting. 04:03:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:03:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:03:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:29 INFO - (blobuploader) - INFO - Done attempting. 04:03:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:03:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:03:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:33 INFO - (blobuploader) - INFO - Done attempting. 04:03:33 INFO - (blobuploader) - INFO - Iteration through files over. 04:03:33 INFO - Return code: 0 04:03:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:03:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:03:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ce30ccd1b7e690aec24f6bd831aaeb63eb4296539ecaaa57325f046e251ca50119660f9cccf239e3c8624043d2feb12cf24f9d55728920be70347f4710f1013", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e170679b4f179ce881370db76ecddad4f11b114f7486ce0d03a9236f8a75544c66e1db9e54a90e77bc11fcb6ec639bcb3793dd740589b28dbaf31b3a1319a89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ba00a035794d5921dfefea8514c316db3434356ff4ea526c3b7f35f62da8bf7bfcf0c65106c90b61f410ed869b5c758131be301b21d15619f88d068ce091446"} 04:03:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:03:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:03:33 INFO - Contents: 04:03:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ce30ccd1b7e690aec24f6bd831aaeb63eb4296539ecaaa57325f046e251ca50119660f9cccf239e3c8624043d2feb12cf24f9d55728920be70347f4710f1013", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e170679b4f179ce881370db76ecddad4f11b114f7486ce0d03a9236f8a75544c66e1db9e54a90e77bc11fcb6ec639bcb3793dd740589b28dbaf31b3a1319a89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ba00a035794d5921dfefea8514c316db3434356ff4ea526c3b7f35f62da8bf7bfcf0c65106c90b61f410ed869b5c758131be301b21d15619f88d068ce091446"} 04:03:33 INFO - Running post-run listener: copy_logs_to_upload_dir 04:03:33 INFO - Copying logs to upload dir... 04:03:33 INFO - mkdir: /builds/slave/test/build/upload/logs 04:03:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.704118 ========= master_lag: 46.87 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 44 secs) (at 2016-09-13 04:04:19.856689) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 04:04:19.860419) ========= 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=652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ce30ccd1b7e690aec24f6bd831aaeb63eb4296539ecaaa57325f046e251ca50119660f9cccf239e3c8624043d2feb12cf24f9d55728920be70347f4710f1013", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e170679b4f179ce881370db76ecddad4f11b114f7486ce0d03a9236f8a75544c66e1db9e54a90e77bc11fcb6ec639bcb3793dd740589b28dbaf31b3a1319a89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ba00a035794d5921dfefea8514c316db3434356ff4ea526c3b7f35f62da8bf7bfcf0c65106c90b61f410ed869b5c758131be301b21d15619f88d068ce091446"} build_url:https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011246 build_url: 'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ce30ccd1b7e690aec24f6bd831aaeb63eb4296539ecaaa57325f046e251ca50119660f9cccf239e3c8624043d2feb12cf24f9d55728920be70347f4710f1013", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e170679b4f179ce881370db76ecddad4f11b114f7486ce0d03a9236f8a75544c66e1db9e54a90e77bc11fcb6ec639bcb3793dd740589b28dbaf31b3a1319a89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ba00a035794d5921dfefea8514c316db3434356ff4ea526c3b7f35f62da8bf7bfcf0c65106c90b61f410ed869b5c758131be301b21d15619f88d068ce091446"}' ========= master_lag: 0.77 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 04:04:20.640676) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 04:04:20.644108) ========= 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=652eb03db935cfc1c15ebda0000001ad-1473755366.779376-52611839 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004162 ========= master_lag: 0.30 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 04:04:20.943594) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 04:04:20.944010) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 04:04:20.993760) ========= ========= Total master_lag: 47.99 =========