builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1472156206.96 results: success (0) buildid: 20160825102025 builduid: d77857547f144b80af5516ccae4a5182 revision: fa1adee0fb582bc6ae5542047d09b02a37e2a6f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:46.963201) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:46.963625) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:46.963894) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:46.978442) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:46.978797) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-25 13:16:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-08-25 13:16:48 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106227 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:47.099009) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:47.099327) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014796 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:47.148706) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:47.149073) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa1adee0fb582bc6ae5542047d09b02a37e2a6f9 --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 fa1adee0fb582bc6ae5542047d09b02a37e2a6f9 --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=e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-25 13:16:48,319 truncating revision to first 12 chars 2016-08-25 13:16:48,319 Setting DEBUG logging. 2016-08-25 13:16:48,319 attempt 1/10 2016-08-25 13:16:48,319 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fa1adee0fb58?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 13:16:48,677 unpacking tar archive at: mozilla-aurora-fa1adee0fb58/testing/mozharness/ program finished with exit code 0 elapsedTime=0.682365 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:47.844657) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:47.844976) ========= script_repo_revision: fa1adee0fb582bc6ae5542047d09b02a37e2a6f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:47.845362) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:47.845621) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 13:16:47.853358) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-25 13:16:47.853687) ========= /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=e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:16:49 INFO - MultiFileLogger online at 20160825 13:16:49 in /builds/slave/test 13:16:49 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 13:16:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:16:49 INFO - {'append_to_log': False, 13:16:49 INFO - 'base_work_dir': '/builds/slave/test', 13:16:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:16:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:16:49 INFO - 'branch': 'Mozilla-Aurora', 13:16:49 INFO - 'buildbot_json_path': 'buildprops.json', 13:16:49 INFO - 'config_files': ('talos/linux_config.py',), 13:16:49 INFO - 'default_actions': ('clobber', 13:16:49 INFO - 'read-buildbot-config', 13:16:49 INFO - 'download-and-extract', 13:16:49 INFO - 'populate-webroot', 13:16:49 INFO - 'create-virtualenv', 13:16:49 INFO - 'install', 13:16:49 INFO - 'run-tests'), 13:16:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:16:49 INFO - 'download_minidump_stackwalk': True, 13:16:49 INFO - 'download_symbols': 'ondemand', 13:16:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:16:49 INFO - 'tooltool.py': '/tools/tooltool.py', 13:16:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:16:49 INFO - '/tools/misc-python/virtualenv.py')}, 13:16:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:16:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:16:49 INFO - 'installer_path': 'installer.exe', 13:16:49 INFO - 'log_level': 'info', 13:16:49 INFO - 'log_name': 'talos', 13:16:49 INFO - 'log_to_console': True, 13:16:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:16:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:16:49 INFO - 'opt_config_files': (), 13:16:49 INFO - 'pip_index': False, 13:16:49 INFO - 'sps_profile': False, 13:16:49 INFO - 'sps_profile_interval': 0, 13:16:49 INFO - 'suite': 'dromaeojs', 13:16:49 INFO - 'system_bits': '32', 13:16:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:16:49 INFO - 'title': 'talos-linux64-ix-067', 13:16:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:16:49 INFO - 'use_talos_json': True, 13:16:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:16:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:16:49 INFO - 'work_dir': 'build'} 13:16:49 INFO - [mozharness: 2016-08-25 20:16:49.116888Z] Running clobber step. 13:16:49 INFO - Running pre-action listener: _resource_record_pre_action 13:16:49 INFO - Running main action method: clobber 13:16:49 INFO - rmtree: /builds/slave/test/build 13:16:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:16:49 INFO - Running post-action listener: _resource_record_post_action 13:16:49 INFO - [mozharness: 2016-08-25 20:16:49.594374Z] Finished clobber step (success) 13:16:49 INFO - [mozharness: 2016-08-25 20:16:49.594474Z] Running read-buildbot-config step. 13:16:49 INFO - Running pre-action listener: _resource_record_pre_action 13:16:49 INFO - Running main action method: read_buildbot_config 13:16:49 INFO - Using buildbot properties: 13:16:49 INFO - { 13:16:49 INFO - "project": "", 13:16:49 INFO - "product": "firefox", 13:16:49 INFO - "script_repo_revision": "production", 13:16:49 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:16:49 INFO - "repository": "", 13:16:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:16:49 INFO - "buildid": "20160825102025", 13:16:49 INFO - "pgo_build": "True", 13:16:49 INFO - "basedir": "/builds/slave/test", 13:16:49 INFO - "buildnumber": 492, 13:16:49 INFO - "slavename": "talos-linux64-ix-067", 13:16:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:16:49 INFO - "platform": "ubuntu64_hw", 13:16:49 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:16:49 INFO - "branch": "mozilla-aurora", 13:16:49 INFO - "repo_path": "releases/mozilla-aurora", 13:16:49 INFO - "revision": "fa1adee0fb582bc6ae5542047d09b02a37e2a6f9", 13:16:49 INFO - "stage_platform": "linux64-pgo", 13:16:49 INFO - "builduid": "d77857547f144b80af5516ccae4a5182", 13:16:49 INFO - "slavebuilddir": "test" 13:16:49 INFO - } 13:16:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 13:16:49 INFO - Running post-action listener: _resource_record_post_action 13:16:49 INFO - [mozharness: 2016-08-25 20:16:49.597009Z] Finished read-buildbot-config step (success) 13:16:49 INFO - [mozharness: 2016-08-25 20:16:49.597098Z] Running download-and-extract step. 13:16:49 INFO - Running pre-action listener: _resource_record_pre_action 13:16:49 INFO - Running main action method: download_and_extract 13:16:49 INFO - mkdir: /builds/slave/test/build/tests 13:16:49 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')]} 13:16:49 INFO - https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:16:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:16:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:16:49 INFO - retry: Failed, sleeping 30 seconds before retrying 13:17:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:17:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:17:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:17:19 INFO - retry: Failed, sleeping 60 seconds before retrying 13:18:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:18:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:18:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:18:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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! 13:18:19 INFO - Caught exception: HTTP Error 404: Not Found 13:18:19 INFO - Caught exception: HTTP Error 404: Not Found 13:18:19 INFO - Caught exception: HTTP Error 404: Not Found 13:18:19 INFO - trying https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:18:19 INFO - Downloading https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:18:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:18:20 INFO - Downloaded 1361 bytes. 13:18:20 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:18:20 INFO - Using the following test package requirements: 13:18:20 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:18:20 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:18:20 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:18:20 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:20 INFO - u'jsshell-linux-x86_64.zip'], 13:18:20 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:18:20 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:18:20 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:18:20 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:18:20 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:18:20 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:18:20 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:18: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')]} 13:18:20 INFO - https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:18:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:18:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:18:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:18:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:18:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:18:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:18:20 INFO - retry: Failed, sleeping 30 seconds before retrying 13:18:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:18:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:18:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:18:50 INFO - retry: Failed, sleeping 60 seconds before retrying 13:19:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:19:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:19:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:19:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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! 13:19:50 INFO - Caught exception: HTTP Error 404: Not Found 13:19:50 INFO - Caught exception: HTTP Error 404: Not Found 13:19:50 INFO - Caught exception: HTTP Error 404: Not Found 13:19:50 INFO - trying https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:19:50 INFO - Downloading https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:19:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:19:51 INFO - Downloaded 21836993 bytes. 13:19:51 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'] 13:19:51 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 13:19:52 INFO - Return code: 0 13:19:52 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 13:19:52 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')]} 13:19:52 INFO - https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:19:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:19:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:19:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:19:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:19:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:19:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:19:52 INFO - retry: Failed, sleeping 30 seconds before retrying 13:20:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:20:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:20:22 INFO - retry: Failed, sleeping 60 seconds before retrying 13:21:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:21:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:21:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:21:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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! 13:21:22 INFO - Caught exception: HTTP Error 404: Not Found 13:21:22 INFO - Caught exception: HTTP Error 404: Not Found 13:21:22 INFO - Caught exception: HTTP Error 404: Not Found 13:21:22 INFO - trying https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:21:22 INFO - Downloading https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:21:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:21:23 INFO - Downloaded 21836993 bytes. 13:21:23 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'] 13:21:23 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 13:21:24 INFO - Return code: 0 13:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:24 INFO - https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:21:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:21:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:21:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:21:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:21:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:21:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:21:24 INFO - retry: Failed, sleeping 30 seconds before retrying 13:21:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:21:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:21:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:21:54 INFO - retry: Failed, sleeping 60 seconds before retrying 13:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:22:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:22:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:22:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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! 13:22:54 INFO - Caught exception: HTTP Error 404: Not Found 13:22:54 INFO - Caught exception: HTTP Error 404: Not Found 13:22:54 INFO - Caught exception: HTTP Error 404: Not Found 13:22:54 INFO - trying https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:22:54 INFO - Downloading https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:22:55 INFO - Downloaded 14413723 bytes. 13:22:55 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'] 13:22:55 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 13:22:55 INFO - Return code: 0 13:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:22:55 INFO - https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:22:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:22:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:22:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:22:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:22:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:22:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:22:55 INFO - retry: Failed, sleeping 30 seconds before retrying 13:23:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:23:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:23:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:23:25 INFO - retry: Failed, sleeping 60 seconds before retrying 13:24:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:24:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:24:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:24:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LG8y-wzRQL2shaU_7OafnQ/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! 13:24:25 INFO - Caught exception: HTTP Error 404: Not Found 13:24:25 INFO - Caught exception: HTTP Error 404: Not Found 13:24:25 INFO - Caught exception: HTTP Error 404: Not Found 13:24:25 INFO - trying https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:24:25 INFO - Downloading https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:24:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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 13:24:27 INFO - Downloaded 63755641 bytes. 13:24:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:24:27 INFO - mkdir: /builds/slave/test/properties 13:24:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:24:27 INFO - Writing to file /builds/slave/test/properties/build_url 13:24:27 INFO - Contents: 13:24:27 INFO - build_url:https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:24:28 INFO - Running post-action listener: _resource_record_post_action 13:24:28 INFO - Running post-action listener: set_extra_try_arguments 13:24:28 INFO - [mozharness: 2016-08-25 20:24:28.170214Z] Finished download-and-extract step (success) 13:24:28 INFO - [mozharness: 2016-08-25 20:24:28.170446Z] Running populate-webroot step. 13:24:28 INFO - Running pre-action listener: _resource_record_pre_action 13:24:28 INFO - Running main action method: populate_webroot 13:24:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:24:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:24:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:24:28 INFO - '--tppagecycles', 13:24:28 INFO - '7'], 13:24:28 INFO - 'tests': ['tsvgm']}}, 13:24:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:24:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:24:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:24:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:24:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:24:28 INFO - 'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:24:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:24:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:24:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:24:28 INFO - 'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['damp', 'tps']}, 13:24:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:24:28 INFO - 'tests': ['damp', 'tps']}, 13:24:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['dromaeo_dom']}, 13:24:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:24:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['basic_compositor_video']}, 13:24:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:24:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['a11yr', 13:24:28 INFO - 'ts_paint', 13:24:28 INFO - 'tpaint', 13:24:28 INFO - 'sessionrestore', 13:24:28 INFO - 'sessionrestore_no_auto_restore', 13:24:28 INFO - 'tabpaint']}, 13:24:28 INFO - 'other-e10s': {'tests': ['a11yr', 13:24:28 INFO - 'ts_paint', 13:24:28 INFO - 'tpaint', 13:24:28 INFO - 'sessionrestore', 13:24:28 INFO - 'sessionrestore_no_auto_restore', 13:24:28 INFO - 'tabpaint']}, 13:24:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:24:28 INFO - 'ts_paint', 13:24:28 INFO - 'tpaint', 13:24:28 INFO - 'sessionrestore', 13:24:28 INFO - 'sessionrestore_no_auto_restore', 13:24:28 INFO - 'tabpaint']}, 13:24:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:24:28 INFO - 'ts_paint', 13:24:28 INFO - 'tpaint', 13:24:28 INFO - 'sessionrestore', 13:24:28 INFO - 'sessionrestore_no_auto_restore', 13:24:28 INFO - 'tabpaint']}, 13:24:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['a11yr', 13:24:28 INFO - 'ts_paint', 13:24:28 INFO - 'tpaint', 13:24:28 INFO - 'sessionrestore', 13:24:28 INFO - 'sessionrestore_no_auto_restore', 13:24:28 INFO - 'tabpaint']}, 13:24:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['a11yr', 13:24:28 INFO - 'ts_paint', 13:24:28 INFO - 'tpaint', 13:24:28 INFO - 'sessionrestore', 13:24:28 INFO - 'sessionrestore_no_auto_restore', 13:24:28 INFO - 'tabpaint']}, 13:24:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['tsvgx', 13:24:28 INFO - 'tsvgr_opacity', 13:24:28 INFO - 'tart', 13:24:28 INFO - 'tscrollx', 13:24:28 INFO - 'cart']}, 13:24:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:24:28 INFO - 'tsvgr_opacity', 13:24:28 INFO - 'tart', 13:24:28 INFO - 'tscrollx', 13:24:28 INFO - 'cart']}, 13:24:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:24:28 INFO - 'talos_options': ['--disable-e10s'], 13:24:28 INFO - 'tests': ['tp5o']}, 13:24:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:24:28 INFO - 'tests': ['tp5o']}, 13:24:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:24:28 INFO - 'talos_options': ['--disable-e10s', 13:24:28 INFO - '--xperf_path', 13:24:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:24:28 INFO - 'tests': ['tp5n']}, 13:24:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:24:28 INFO - 'talos_options': ['--xperf_path', 13:24:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:24:28 INFO - 'tests': ['tp5n']}}, 13:24:28 INFO - 'talos.zip': {'path': '', 13:24:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:24:28 INFO - Running post-action listener: _resource_record_post_action 13:24:28 INFO - [mozharness: 2016-08-25 20:24:28.192740Z] Finished populate-webroot step (success) 13:24:28 INFO - [mozharness: 2016-08-25 20:24:28.192830Z] Running create-virtualenv step. 13:24:28 INFO - Running pre-action listener: _resource_record_pre_action 13:24:28 INFO - Running main action method: create_virtualenv 13:24:28 INFO - Creating virtualenv /builds/slave/test/build/venv 13:24:28 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 13:24:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:24:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:24:28 INFO - Using real prefix '/usr' 13:24:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:24:28 INFO - Installing distribute.............................................................................................................................................................................................done. 13:24:30 INFO - Installing pip.................done. 13:24:30 INFO - Return code: 0 13:24:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:30 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 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24:30 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 13:24:30 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 13:24:30 INFO - Using env: {'DISPLAY': ':0', 13:24:30 INFO - 'HOME': '/home/cltbld', 13:24:30 INFO - 'LANG': 'en_US.UTF-8', 13:24:30 INFO - 'LANGUAGE': 'en_US:en', 13:24:30 INFO - 'LOGNAME': 'cltbld', 13:24:30 INFO - 'MAIL': '/var/mail/cltbld', 13:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:30 INFO - 'MOZ_NO_REMOTE': '1', 13:24:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:30 INFO - 'NO_EM_RESTART': '1', 13:24:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:30 INFO - 'PWD': '/builds/slave/test', 13:24:30 INFO - 'SHELL': '/bin/bash', 13:24:30 INFO - 'SHLVL': '1', 13:24:30 INFO - 'TERM': 'linux', 13:24:30 INFO - 'TMOUT': '86400', 13:24:30 INFO - 'USER': 'cltbld', 13:24:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:30 INFO - '_': '/tools/buildbot/bin/python'} 13:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:30 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 13:24:30 INFO - Cleaning up... 13:24:30 INFO - Return code: 0 13:24:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:30 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 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24:30 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 13:24:30 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 13:24:30 INFO - Using env: {'DISPLAY': ':0', 13:24:30 INFO - 'HOME': '/home/cltbld', 13:24:30 INFO - 'LANG': 'en_US.UTF-8', 13:24:30 INFO - 'LANGUAGE': 'en_US:en', 13:24:30 INFO - 'LOGNAME': 'cltbld', 13:24:30 INFO - 'MAIL': '/var/mail/cltbld', 13:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:30 INFO - 'MOZ_NO_REMOTE': '1', 13:24:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:30 INFO - 'NO_EM_RESTART': '1', 13:24:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:30 INFO - 'PWD': '/builds/slave/test', 13:24:30 INFO - 'SHELL': '/bin/bash', 13:24:30 INFO - 'SHLVL': '1', 13:24:30 INFO - 'TERM': 'linux', 13:24:30 INFO - 'TMOUT': '86400', 13:24:30 INFO - 'USER': 'cltbld', 13:24:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:30 INFO - '_': '/tools/buildbot/bin/python'} 13:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:31 INFO - Downloading/unpacking psutil>=3.1.1 13:24:31 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 13:24:31 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 13:24:31 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 13:24:31 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 13:24:31 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 13:24:31 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 13:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:24:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:24:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:24:33 INFO - Installing collected packages: psutil 13:24:33 INFO - Running setup.py install for psutil 13:24:33 INFO - building 'psutil._psutil_linux' extension 13:24:33 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 13:24:33 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 13:24:33 INFO - building 'psutil._psutil_posix' extension 13:24:33 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 13:24:33 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 13:24:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:24:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:24:34 INFO - Successfully installed psutil 13:24:34 INFO - Cleaning up... 13:24:34 INFO - Return code: 0 13:24:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:34 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 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24:34 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 13:24:34 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 13:24:34 INFO - Using env: {'DISPLAY': ':0', 13:24:34 INFO - 'HOME': '/home/cltbld', 13:24:34 INFO - 'LANG': 'en_US.UTF-8', 13:24:34 INFO - 'LANGUAGE': 'en_US:en', 13:24:34 INFO - 'LOGNAME': 'cltbld', 13:24:34 INFO - 'MAIL': '/var/mail/cltbld', 13:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:34 INFO - 'MOZ_NO_REMOTE': '1', 13:24:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:34 INFO - 'NO_EM_RESTART': '1', 13:24:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:34 INFO - 'PWD': '/builds/slave/test', 13:24:34 INFO - 'SHELL': '/bin/bash', 13:24:34 INFO - 'SHLVL': '1', 13:24:34 INFO - 'TERM': 'linux', 13:24:34 INFO - 'TMOUT': '86400', 13:24:34 INFO - 'USER': 'cltbld', 13:24:34 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:34 INFO - '_': '/tools/buildbot/bin/python'} 13:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:24:34 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 13:24:34 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 13:24:34 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 13:24:34 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 13:24:34 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 13:24:34 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 13:24:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:24:36 INFO - Installing collected packages: mozsystemmonitor 13:24:36 INFO - Running setup.py install for mozsystemmonitor 13:24:36 INFO - Successfully installed mozsystemmonitor 13:24:36 INFO - Cleaning up... 13:24:36 INFO - Return code: 0 13:24:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:24: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')]} 13:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24: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')]} 13:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24: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==2.5.1'] in /builds/slave/test/build 13:24: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==2.5.1 13:24:36 INFO - Using env: {'DISPLAY': ':0', 13:24:36 INFO - 'HOME': '/home/cltbld', 13:24:36 INFO - 'LANG': 'en_US.UTF-8', 13:24:36 INFO - 'LANGUAGE': 'en_US:en', 13:24:36 INFO - 'LOGNAME': 'cltbld', 13:24:36 INFO - 'MAIL': '/var/mail/cltbld', 13:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:36 INFO - 'MOZ_NO_REMOTE': '1', 13:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:36 INFO - 'NO_EM_RESTART': '1', 13:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:36 INFO - 'PWD': '/builds/slave/test', 13:24:36 INFO - 'SHELL': '/bin/bash', 13:24:36 INFO - 'SHLVL': '1', 13:24:36 INFO - 'TERM': 'linux', 13:24:36 INFO - 'TMOUT': '86400', 13:24:36 INFO - 'USER': 'cltbld', 13:24:36 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:36 INFO - '_': '/tools/buildbot/bin/python'} 13:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:37 INFO - Downloading/unpacking jsonschema==2.5.1 13:24:37 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 13:24:37 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 13:24:37 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 13:24:37 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 13:24:37 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 13:24:37 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 13:24:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:24:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:24:39 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 13:24:39 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 13:24:39 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 13:24:39 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 13:24:39 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 13:24:39 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 13:24:39 INFO - Downloading functools32-3.2.3-2.tar.gz 13:24:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:24:39 INFO - warning: no files found matching '*.txt' 13:24:39 INFO - no previously-included directories found matching 'build' 13:24:39 INFO - no previously-included directories found matching 'dist' 13:24:39 INFO - no previously-included directories found matching '.git*' 13:24:39 INFO - Installing collected packages: jsonschema, functools32 13:24:39 INFO - Running setup.py install for functools32 13:24:39 INFO - warning: no files found matching '*.txt' 13:24:39 INFO - no previously-included directories found matching 'build' 13:24:39 INFO - no previously-included directories found matching 'dist' 13:24:39 INFO - no previously-included directories found matching '.git*' 13:24:39 INFO - Successfully installed jsonschema functools32 13:24:39 INFO - Cleaning up... 13:24:39 INFO - Return code: 0 13:24:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:24: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')]} 13:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24: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')]} 13:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:39 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 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24:39 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 13:24:39 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 13:24:39 INFO - Using env: {'DISPLAY': ':0', 13:24:39 INFO - 'HOME': '/home/cltbld', 13:24:39 INFO - 'LANG': 'en_US.UTF-8', 13:24:39 INFO - 'LANGUAGE': 'en_US:en', 13:24:39 INFO - 'LOGNAME': 'cltbld', 13:24:39 INFO - 'MAIL': '/var/mail/cltbld', 13:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:39 INFO - 'MOZ_NO_REMOTE': '1', 13:24:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:39 INFO - 'NO_EM_RESTART': '1', 13:24:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:39 INFO - 'PWD': '/builds/slave/test', 13:24:39 INFO - 'SHELL': '/bin/bash', 13:24:39 INFO - 'SHLVL': '1', 13:24:39 INFO - 'TERM': 'linux', 13:24:39 INFO - 'TMOUT': '86400', 13:24:39 INFO - 'USER': 'cltbld', 13:24:39 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:39 INFO - '_': '/tools/buildbot/bin/python'} 13:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:24:40 INFO - Cleaning up... 13:24:40 INFO - Return code: 0 13:24:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:24:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:24:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:24:40 INFO - Using env: {'DISPLAY': ':0', 13:24:40 INFO - 'HOME': '/home/cltbld', 13:24:40 INFO - 'LANG': 'en_US.UTF-8', 13:24:40 INFO - 'LANGUAGE': 'en_US:en', 13:24:40 INFO - 'LOGNAME': 'cltbld', 13:24:40 INFO - 'MAIL': '/var/mail/cltbld', 13:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:40 INFO - 'MOZ_NO_REMOTE': '1', 13:24:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:40 INFO - 'NO_EM_RESTART': '1', 13:24:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:40 INFO - 'PWD': '/builds/slave/test', 13:24:40 INFO - 'SHELL': '/bin/bash', 13:24:40 INFO - 'SHLVL': '1', 13:24:40 INFO - 'TERM': 'linux', 13:24:40 INFO - 'TMOUT': '86400', 13:24:40 INFO - 'USER': 'cltbld', 13:24:40 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:40 INFO - '_': '/tools/buildbot/bin/python'} 13:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:40 INFO - Downloading/unpacking blobuploader==1.2.4 13:24:40 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 13:24:40 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 13:24:40 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 13:24:40 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 13:24:40 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 13:24:40 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 13:24:42 INFO - Downloading blobuploader-1.2.4.tar.gz 13:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:24:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:24:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:24:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:24:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:24:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:24:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:24:42 INFO - Downloading docopt-0.6.1.tar.gz 13:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:24:42 INFO - Installing collected packages: blobuploader, requests, docopt 13:24:42 INFO - Running setup.py install for blobuploader 13:24:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:24:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:24:43 INFO - Running setup.py install for requests 13:24:43 INFO - Running setup.py install for docopt 13:24:43 INFO - Successfully installed blobuploader requests docopt 13:24:43 INFO - Cleaning up... 13:24:43 INFO - Return code: 0 13:24:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:43 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 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24:43 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 13:24:43 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 13:24:43 INFO - Using env: {'DISPLAY': ':0', 13:24:43 INFO - 'HOME': '/home/cltbld', 13:24:43 INFO - 'LANG': 'en_US.UTF-8', 13:24:43 INFO - 'LANGUAGE': 'en_US:en', 13:24:43 INFO - 'LOGNAME': 'cltbld', 13:24:43 INFO - 'MAIL': '/var/mail/cltbld', 13:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:43 INFO - 'MOZ_NO_REMOTE': '1', 13:24:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:43 INFO - 'NO_EM_RESTART': '1', 13:24:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:43 INFO - 'PWD': '/builds/slave/test', 13:24:43 INFO - 'SHELL': '/bin/bash', 13:24:43 INFO - 'SHLVL': '1', 13:24:43 INFO - 'TERM': 'linux', 13:24:43 INFO - 'TMOUT': '86400', 13:24:43 INFO - 'USER': 'cltbld', 13:24:43 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:43 INFO - '_': '/tools/buildbot/bin/python'} 13:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:24:43 INFO - Running setup.py (path:/tmp/pip-CCj6xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:24:43 INFO - Running setup.py (path:/tmp/pip-U6Okgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:24:43 INFO - Running setup.py (path:/tmp/pip-8QQ4Gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:24:44 INFO - Running setup.py (path:/tmp/pip-UCTQzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:24:44 INFO - Running setup.py (path:/tmp/pip-d5Q4Wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:24:44 INFO - Running setup.py (path:/tmp/pip-NYI2Z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:24:44 INFO - Running setup.py (path:/tmp/pip-XsvCvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:24:44 INFO - Running setup.py (path:/tmp/pip-eJTLbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:24:44 INFO - Running setup.py (path:/tmp/pip-q0xTuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:24:44 INFO - Running setup.py (path:/tmp/pip-oW38QT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:24:44 INFO - Running setup.py (path:/tmp/pip-xgEFi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:24:44 INFO - Running setup.py (path:/tmp/pip-BXpp6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:24:44 INFO - Running setup.py (path:/tmp/pip-UGwuto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:24:44 INFO - Running setup.py (path:/tmp/pip-_Axv_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:24:44 INFO - Running setup.py (path:/tmp/pip-9QOrjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:24:44 INFO - Running setup.py (path:/tmp/pip-iaX3tI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:24:44 INFO - Running setup.py (path:/tmp/pip-7D7T42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:24:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:24:45 INFO - Running setup.py install for manifestparser 13:24:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:24:45 INFO - Running setup.py install for mozcrash 13:24:45 INFO - Running setup.py install for mozdebug 13:24:45 INFO - Running setup.py install for mozdevice 13:24:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:24:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:24:45 INFO - Running setup.py install for mozfile 13:24:45 INFO - Running setup.py install for mozhttpd 13:24:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:24:45 INFO - Running setup.py install for mozinfo 13:24:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:24:45 INFO - Running setup.py install for mozInstall 13:24:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:24:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:24:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:24:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:24:45 INFO - Running setup.py install for mozleak 13:24:45 INFO - Running setup.py install for mozlog 13:24:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:24:46 INFO - Running setup.py install for moznetwork 13:24:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:24:46 INFO - Running setup.py install for mozprocess 13:24:46 INFO - Running setup.py install for mozprofile 13:24:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:24:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:24:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:24:46 INFO - Running setup.py install for mozrunner 13:24:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:24:46 INFO - Running setup.py install for mozscreenshot 13:24:46 INFO - Running setup.py install for moztest 13:24:46 INFO - Running setup.py install for mozversion 13:24:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:24:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:24:46 INFO - Cleaning up... 13:24:46 INFO - Return code: 0 13:24:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:46 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 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24:46 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 13:24:46 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 13:24:46 INFO - Using env: {'DISPLAY': ':0', 13:24:46 INFO - 'HOME': '/home/cltbld', 13:24:46 INFO - 'LANG': 'en_US.UTF-8', 13:24:46 INFO - 'LANGUAGE': 'en_US:en', 13:24:46 INFO - 'LOGNAME': 'cltbld', 13:24:46 INFO - 'MAIL': '/var/mail/cltbld', 13:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:46 INFO - 'MOZ_NO_REMOTE': '1', 13:24:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:46 INFO - 'NO_EM_RESTART': '1', 13:24:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:46 INFO - 'PWD': '/builds/slave/test', 13:24:46 INFO - 'SHELL': '/bin/bash', 13:24:46 INFO - 'SHLVL': '1', 13:24:46 INFO - 'TERM': 'linux', 13:24:46 INFO - 'TMOUT': '86400', 13:24:46 INFO - 'USER': 'cltbld', 13:24:46 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:46 INFO - '_': '/tools/buildbot/bin/python'} 13:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:24:47 INFO - Running setup.py (path:/tmp/pip-lDwQNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:24:47 INFO - Running setup.py (path:/tmp/pip-xxaqqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:24:47 INFO - Running setup.py (path:/tmp/pip-Ac1JSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:24:47 INFO - Running setup.py (path:/tmp/pip-jEaQpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:24:47 INFO - Running setup.py (path:/tmp/pip-V6weNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:24:47 INFO - Running setup.py (path:/tmp/pip-LMOlYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:24:47 INFO - Running setup.py (path:/tmp/pip-8cQRNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:24:47 INFO - Running setup.py (path:/tmp/pip-A2FaKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:24:47 INFO - Running setup.py (path:/tmp/pip-ziS6AA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:24:47 INFO - Running setup.py (path:/tmp/pip-rV8OXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:24:47 INFO - Running setup.py (path:/tmp/pip-uEtjQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:24:47 INFO - Running setup.py (path:/tmp/pip-ke37cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:24:47 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)) 13:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:24:47 INFO - Running setup.py (path:/tmp/pip-zM2o2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:24:48 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)) 13:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:24:48 INFO - Running setup.py (path:/tmp/pip-1w7Uhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:24:48 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)) 13:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:24:48 INFO - Running setup.py (path:/tmp/pip-Ft4tVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:24:48 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)) 13:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:24:48 INFO - Running setup.py (path:/tmp/pip-dN_4mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:24:48 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)) 13:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:24:48 INFO - Running setup.py (path:/tmp/pip-n7RL8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:24:48 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)) 13:24:48 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)) 13:24:48 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)) 13:24:48 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)) 13:24:48 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)) 13:24:48 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)) 13:24:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:24:48 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 13:24:48 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 13:24:48 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 13:24:48 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 13:24:48 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 13:24:48 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 13:24:50 INFO - Downloading blessings-1.6.tar.gz 13:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:24:50 INFO - Installing collected packages: blessings 13:24:50 INFO - Running setup.py install for blessings 13:24:50 INFO - Successfully installed blessings 13:24:50 INFO - Cleaning up... 13:24:50 INFO - Return code: 0 13:24:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:24:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:24:50 INFO - Reading from file tmpfile_stdout 13:24:50 INFO - Current package versions: 13:24:50 INFO - argparse == 1.2.1 13:24:50 INFO - blessings == 1.6 13:24:50 INFO - blobuploader == 1.2.4 13:24:50 INFO - docopt == 0.6.1 13:24:50 INFO - functools32 == 3.2.3-2 13:24:50 INFO - jsonschema == 2.5.1 13:24:50 INFO - manifestparser == 1.1 13:24:50 INFO - mozInstall == 1.12 13:24:50 INFO - mozcrash == 0.17 13:24:50 INFO - mozdebug == 0.1 13:24:50 INFO - mozdevice == 0.48 13:24:50 INFO - mozfile == 1.2 13:24:50 INFO - mozhttpd == 0.7 13:24:50 INFO - mozinfo == 0.9 13:24:50 INFO - mozleak == 0.1 13:24:50 INFO - mozlog == 3.3 13:24:50 INFO - moznetwork == 0.27 13:24:50 INFO - mozprocess == 0.23 13:24:50 INFO - mozprofile == 0.28 13:24:50 INFO - mozrunner == 6.12 13:24:50 INFO - mozscreenshot == 0.1 13:24:50 INFO - mozsystemmonitor == 0.3 13:24:50 INFO - moztest == 0.7 13:24:50 INFO - mozversion == 1.4 13:24:50 INFO - psutil == 3.1.1 13:24:50 INFO - requests == 1.2.3 13:24:50 INFO - wsgiref == 0.1.2 13:24:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:50 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 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24:50 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 13:24:50 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 13:24:50 INFO - Using env: {'DISPLAY': ':0', 13:24:50 INFO - 'HOME': '/home/cltbld', 13:24:50 INFO - 'LANG': 'en_US.UTF-8', 13:24:50 INFO - 'LANGUAGE': 'en_US:en', 13:24:50 INFO - 'LOGNAME': 'cltbld', 13:24:50 INFO - 'MAIL': '/var/mail/cltbld', 13:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:50 INFO - 'MOZ_NO_REMOTE': '1', 13:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:50 INFO - 'NO_EM_RESTART': '1', 13:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:50 INFO - 'PWD': '/builds/slave/test', 13:24:50 INFO - 'SHELL': '/bin/bash', 13:24:50 INFO - 'SHLVL': '1', 13:24:50 INFO - 'TERM': 'linux', 13:24:50 INFO - 'TMOUT': '86400', 13:24:50 INFO - 'USER': 'cltbld', 13:24:50 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:50 INFO - '_': '/tools/buildbot/bin/python'} 13:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:51 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)) 13:24:51 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)) 13:24:51 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)) 13:24:51 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)) 13:24:51 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)) 13:24:51 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)) 13:24:51 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)) 13:24:51 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)) 13:24:51 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)) 13:24:51 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)) 13:24:51 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)) 13:24:51 INFO - Cleaning up... 13:24:51 INFO - Return code: 0 13:24:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:24:51 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')]} 13:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:51 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')]} 13:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:51 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 0x1390030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1651180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', '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 13:24:51 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 13:24:51 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 13:24:51 INFO - Using env: {'DISPLAY': ':0', 13:24:51 INFO - 'HOME': '/home/cltbld', 13:24:51 INFO - 'LANG': 'en_US.UTF-8', 13:24:51 INFO - 'LANGUAGE': 'en_US:en', 13:24:51 INFO - 'LOGNAME': 'cltbld', 13:24:51 INFO - 'MAIL': '/var/mail/cltbld', 13:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:51 INFO - 'MOZ_NO_REMOTE': '1', 13:24:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:24:51 INFO - 'NO_EM_RESTART': '1', 13:24:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:51 INFO - 'PWD': '/builds/slave/test', 13:24:51 INFO - 'SHELL': '/bin/bash', 13:24:51 INFO - 'SHLVL': '1', 13:24:51 INFO - 'TERM': 'linux', 13:24:51 INFO - 'TMOUT': '86400', 13:24:51 INFO - 'USER': 'cltbld', 13:24:51 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:51 INFO - '_': '/tools/buildbot/bin/python'} 13:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:24:51 INFO - Cleaning up... 13:24:51 INFO - Return code: 0 13:24:51 INFO - Running post-action listener: _resource_record_post_action 13:24:51 INFO - Running post-action listener: _start_resource_monitoring 13:24:51 INFO - Starting resource monitoring. 13:24:51 INFO - [mozharness: 2016-08-25 20:24:51.361883Z] Finished create-virtualenv step (success) 13:24:51 INFO - [mozharness: 2016-08-25 20:24:51.362319Z] Running install step. 13:24:51 INFO - Running pre-action listener: _resource_record_pre_action 13:24:51 INFO - Running main action method: install 13:24:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:24:51 INFO - Reading from file tmpfile_stdout 13:24:51 INFO - Detecting whether we're running mozinstall >=1.0... 13:24:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:24:51 INFO - Reading from file tmpfile_stdout 13:24:51 INFO - Output received: 13:24:51 INFO - Usage: mozinstall [options] installer 13:24:51 INFO - Options: 13:24:51 INFO - -h, --help show this help message and exit 13:24:51 INFO - -d DEST, --destination=DEST 13:24:51 INFO - Directory to install application into. [default: 13:24:51 INFO - "/builds/slave/test"] 13:24:51 INFO - --app=APP Application being installed. [default: firefox] 13:24:51 INFO - mkdir: /builds/slave/test/build/application 13:24:51 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'] 13:24:51 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 13:25:07 INFO - Reading from file tmpfile_stdout 13:25:07 INFO - Output received: 13:25:07 INFO - /builds/slave/test/build/application/firefox/firefox 13:25:07 INFO - Running post-action listener: _resource_record_post_action 13:25:07 INFO - [mozharness: 2016-08-25 20:25:07.651664Z] Finished install step (success) 13:25:07 INFO - [mozharness: 2016-08-25 20:25:07.651943Z] Running run-tests step. 13:25:07 INFO - Running pre-action listener: _resource_record_pre_action 13:25:07 INFO - Running main action method: run_tests 13:25:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:25:07 INFO - Python 2.7.3 13:25:07 INFO - Return code: 0 13:25:07 INFO - grabbing minidump binary from tooltool 13:25:07 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')]} 13:25:07 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 0x164f3b0>, '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 0x16502b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1650740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:25:07 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 13:25:07 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 13:25:07 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 13:25:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:25:07 INFO - Return code: 0 13:25:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:25:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:25:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:25:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:25:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:25:07 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/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 13:25:07 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/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 13:25:07 INFO - Using env: {'DISPLAY': ':0', 13:25:07 INFO - 'HOME': '/home/cltbld', 13:25:07 INFO - 'LANG': 'en_US.UTF-8', 13:25:07 INFO - 'LANGUAGE': 'en_US:en', 13:25:07 INFO - 'LOGNAME': 'cltbld', 13:25:07 INFO - 'MAIL': '/var/mail/cltbld', 13:25:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:25:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:07 INFO - 'MOZ_NO_REMOTE': '1', 13:25:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:25:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:07 INFO - 'NO_EM_RESTART': '1', 13:25:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:07 INFO - 'PWD': '/builds/slave/test', 13:25:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:25:07 INFO - 'SHELL': '/bin/bash', 13:25:07 INFO - 'SHLVL': '1', 13:25:07 INFO - 'TERM': 'linux', 13:25:07 INFO - 'TMOUT': '86400', 13:25:07 INFO - 'USER': 'cltbld', 13:25:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425', 13:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:07 INFO - '_': '/tools/buildbot/bin/python'} 13:25:07 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/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 13:25:07 INFO - mozversion application_buildid: 20160825102025 13:25:07 INFO - mozversion application_changeset: fa1adee0fb582bc6ae5542047d09b02a37e2a6f9 13:25:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:25:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:25:07 INFO - mozversion application_name: Firefox 13:25:07 INFO - mozversion application_remotingname: firefox-dev 13:25:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:25:07 INFO - mozversion application_vendor: Mozilla 13:25:07 INFO - mozversion application_version: 50.0a2 13:25:07 INFO - mozversion platform_buildid: 20160825102025 13:25:07 INFO - mozversion platform_changeset: fa1adee0fb582bc6ae5542047d09b02a37e2a6f9 13:25:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:25:07 INFO - mozversion platform_version: 50.0a2 13:25:07 INFO - using testdate: 1472156707 13:25:07 INFO - actual date: 1472156707 13:25:07 INFO - starting webserver on 'localhost:46109' 13:25:07 INFO - SUITE-START | Running 2 tests 13:25:07 INFO - TEST-START | dromaeo_css 13:25:07 INFO - operating with platform_type : linux_ 13:25:07 INFO - Initialising browser for dromaeo_css test... 13:25:07 INFO - TEST-INFO | started process 1844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1QLPFK/profile http://localhost:46109/getInfo.html) 13:25:11 INFO - PROCESS | 1844 | __metrics Screen width/height:1600/1200 13:25:11 INFO - PROCESS | 1844 | colorDepth:24 13:25:11 INFO - PROCESS | 1844 | Browser inner width/height: 1024/697 13:25:11 INFO - PROCESS | 1844 | __metrics 13:25:12 INFO - PROCESS | 1844 | 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" 13:25:12 INFO - PROCESS | 1844 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:25:13 INFO - TEST-INFO | 1844: exit 0 13:25:13 INFO - Browser initialized. 13:25:13 INFO - Running cycle 1/1 for dromaeo_css test... 13:25:13 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1QLPFK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:25:24 INFO - PROCESS | 1930 | RSS: Main: 145707008 13:25:24 INFO - PROCESS | 1930 | 13:26:15 INFO - PROCESS | 1930 | 1472156775576 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] 13:28:24 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:46109/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46109/tests/dromaeo/cssquery-ext.html) 13:28:25 INFO - PROCESS | 1930 | RSS: Main: 234541056 13:28:25 INFO - PROCESS | 1930 | 13:31:27 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:46109/tests/dromaeo/cssquery-ext.html (next: http://localhost:46109/tests/dromaeo/cssquery-jquery.html) 13:31:28 INFO - PROCESS | 1930 | RSS: Main: 233152512 13:31:28 INFO - PROCESS | 1930 | 13:34:30 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:46109/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46109/tests/dromaeo/cssquery-mootools.html) 13:34:30 INFO - PROCESS | 1930 | RSS: Main: 207683584 13:34:30 INFO - PROCESS | 1930 | 13:37:32 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:46109/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46109/tests/dromaeo/cssquery-prototype.html) 13:37:33 INFO - PROCESS | 1930 | RSS: Main: 175955968 13:37:33 INFO - PROCESS | 1930 | 13:40:35 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:46109/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46109/tests/dromaeo/cssquery-yui.html) 13:40:36 INFO - PROCESS | 1930 | RSS: Main: 176435200 13:40:36 INFO - PROCESS | 1930 | 13:43:39 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:46109/tests/dromaeo/cssquery-yui.html (next: http://localhost:46109/tests/dromaeo/cssquery-dojo.html) 13:43:39 INFO - PROCESS | 1930 | RSS: Main: 263770112 13:43:39 INFO - PROCESS | 1930 | 13:43:39 INFO - PROCESS | 1930 | __start_tp_report 13:43:39 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 13:43:39 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 13:43:39 INFO - PROCESS | 1930 | |i|pagename|runs| 13:43:39 INFO - PROCESS | 1930 | |0;dojo.html;3226.9193270168244;3237.8219197944113;3253.8535765890524;3264.232905267263;3270.640229574748;5608.691521966291;5712.685802280873;5713.257276554053;5721.742521586528;5734.455226753452;5675.716214189295;5810.448007439287;5820.650760954346;5830.154627578999;5835.270591176103;5125.538701516863;5238.214267859814;5267.578593712513;5282.445343238953;5306.363236411636;5142.768575414116;5250.028744682246;5288.947110528884;5296.523312901838;5314.601404894648;5275.60432967529;5301.867453313659;5315.335583052119;5318.282031925679;5343.091674415339;5377.247185394048;5396.865078373033;5398.811041613564;5406.216098665672;5406.756695948692;5128.871778205575;5130.153524668425;5181.766820493087;5293.682379057231;5304.655197412156;5561.110222364443;5598.356189038232;5634.774609015634;5642.407547207526;5674.858128546819;6107.267127944675;6123.846903827395;6125.264968203799;6125.448709616111;6126.417990290915;6141;6142.836439233798;6145.293291271516;6159.87680246397;6225.87548249037;5803.129530570406;5842.4741773240185;5849;5852.180694702745;5855.736491857877;6116.633002019893;6359.841003974938;6364.077208804697;6369.172007638977;6370.8088757337355;6000.069989151689;6067.635941843503;6069.514438844882;6113.0891616662275;6117.785877494355;6218.284897236786;6223.004319308889;6224.906626400608;6227;6231.688415579203;5909.379515150933;6184.134221209034;6185.134081228632;6201.038838979875;6209.0996805462955;5931.347551769302;5975.073863551156;5994.430529099728;6026.126211699279;6057.818265452043;4583.381243532081;4612.53874612536;4617.053504031665;4621.214393553104;4645.721256724607;4082.897927551835;4111.424400583921;4116.485439320085;4118.155778065489;4125.319322311845;5737.340205876294;5776.191333213354;5797.420257974253;5815;5826.300843898674;6148.938510614926;6152.907706384408;6156.969215153896;6157.261128664589;6160.1375807387;5750.654960702372;5783.421657834183;5813.9534883721335;5820.35976042635;5837.88324233513;5284.154535274338;5346.224797404355;5352.357717073976;5372.113601255827;5374.516293533601;6374.553781235361;6415.871682566416;6426;6426.485881129498;6431.967840160769;6046.455818976137;6055.485283750754;6235.937640623717;6242.344553821783;6263.091851681572;6265.248170219603;6295.559310848196;6321.873562528632;6329.430351268408;6330.936690633035;6008.939910601013;6035.9094613579955;6036.336003039574;6048.879022419615;6048.969755151372;5963.493103086286;6147.108669242845;6155.47678447337;6167.198264225622;6177.073438984188;6121.910283036266;6131.908021379772;6132.661461321519;6134.877302454015;6150.60021098645;6035.9094613579955;6076.240469941257;6084.847878803065;6085.391460853879;6093.969530152321;5831.533477321889;5937.495312898279;6033;6039.728212230372;6053.303870055;6030.48740857032;6117.827404800828;6128.601640893506;6130.417610327012;6132.327596972871;5962.25471816023;5969.492593129459;5970.940290597212;5974.312954010345;6008.24896887889 13:43:39 INFO - PROCESS | 1930 | |1;ext.html;603.942625450582;616.8365383173458;646.476354153136;660.6003367962381;663.2306524431655;36338.27323453529;47772.52227477724;48860.755696221466;48970.51029489704;51333;47438;47790.522094779044;47885.521144788545;47896.28155577669;47943.28085078727;8533.487990720545;9331.066893310683;9484.762880927963;9494.715158545254;11117.72205694856;10907.509162087725;11419.371934543597;11420.371879546621;11427.428628568538;11429.885701143006;11466.827997580043;11512.194146409754;11527.48126334317;11530.65408037756;11532.25040372373;11360.375179365132;11388.658340249764;11434.313941163473;11459.02598279143;11465.598704045318;11243.66269011931;11294.265872718248;11308.151888608387;11323.490442930088;11325.32048077118;46975.060498790175;47262.76368618169;47321.76339118282;47383.76308118437;47408.525914740756;3944.7435916665327;3966.6033396660105;4579.358889755437;4705.670603057787;4754.120487709784;34856.30287394238;35338.82330588331;35399.64600353989;35598.82200589006;37089.62910370889;4687.250039993584;4701;4757.429108507002;4760.166970780099;4834.9516504834855;377799.99999999726;379100;379613;380302;382447;67926.66036669884;68290.31709682939;68294;68438.31561684319;68467.65766171138;40301.39547906784;40883;41086.38370424446;71555;71763.6411817948;19916.60166796656;20017.59964800725;20030.098645560985;20036.799632003786;20170.798292017185;13717.17696938187;13967;14043.368048437846;14063.929680351534;15389.307481163374;21606.351809445743;21690.783092169193;21862.45343866416;21970.89014554949;21978.120875164816;7434.962825185839;7470.4023678106705;7623.275788800055;7624.8475030500185;7905.209479052164;1041.4844651897358;1090.9781804363868;1120.355795417638;1135.432283858071;1158.9246698964541;34652.65347346545;34815.30369392598;35117.82441087778;35196.47205291923;35355;1140.680609429361;1153.5847095045776;1200.4117982188666;1236.3509157692283;1297.5199176304827;18711;18745.156467958976;18910.338138165098;18940.337088202123;18995.335163269217;628557;634294;636063;636939;640313;65454.34545654578;66004.33995660079;66124.33875661182;66125.6693716538;66149.33850661528;1357.8166947461966;1434.211183848879;1479.2751551739784;1483.3547406878258;1500.7918625506572;33444;35499.64500354963;35751.28497430089;35906.4614030795;35917.28165436624;56222.71888640694;56762;56769;56850;56955;459053;462021;462657;462881;463928;30526;31207.687923121386;31747.84126079355;33019.504707428925;33221.50167747534;52304.73847630737;52413.475865242384;53112;53116.73441632767;53412.73293633662;43444.532961562196;44901;45295.547044529136;45609.771951140036;47085.76457117692;44712;45853.31220031769;45963.54036459592;46442.08537403919;46534.30198547092;44634;45359;45985.08029839318;46160;46528;38395;39909.80045099756;40062.79968600138;40555.59444405518;40908.0334027987;28518.714812852435;29014.27464313409;29045.85477072601;29178;29254.561181582718 13:43:39 INFO - PROCESS | 1930 | |2;jquery.html;401.4540225293602;413.2561389498901;429.2059689574287;431.44990137574587;437.5864807756669;15269;19987.100580473878;20155.798442015595;20708.585828283445;20863.37409877702;20236.797632023674;20287.18851245952;20305;20354.08406621702;20537.691934620994;5795.4784069433745;5855.063189889618;5864.325602555718;5899.6165249258875;5906.822795316126;5786;5839.036558967765;5851.093080572497;5852.297724273072;5853.560982926277;5733.541316694655;5735.053716136853;5755.856103597402;5791.247137872071;5878.2064421303;5562.471565201299;5843.766249350021;5857.648541087548;5865.011791981451;5865.442782935615;5146.073706732786;5278.049951008817;5299.125644268691;5300.734963251822;5303.151495760699;24158;24298.878505607536;24393.63409548858;24516.387090322885;24704.258872233862;2653.509100816355;2666.3600735823456;2747.4779791839687;3070.47801873682;3343.264481813997;15254.38982440701;15450.690986180325;15498.457553985669;15500.922495387564;15513;2060.783617720133;2108.1988844239245;2119.9576008479744;2135.626265403548;2192.3751730756817;54256;58204.708976454844;65899.34100659029;67518.66240668815;80555.99999999942;11234.55061797519;11334.263272887236;11452.141089418326;11592.88407115918;11709.824352634718;16079.43721969749;16114.919425402797;16187.838121618868;16190.59523511898;16203.918980405258;9095.863562046574;9122.86315705265;9136.314776391797;9191.63233470667;9358.719238422858;8802.735917922473;9053.139951704577;9066.637334506679;9211.355205135576;9256.352055356061;7047.506674532733;7134.500584959107;7146.642667866586;7169.24722904098;7233.421326293884;1980.227711192636;1985.5731017831235;1989.3634037107986;2096.7379077615296;2112.5669237806214;932.8265142218614;996.0985308296003;1009.8737657792776;1025.1491262252312;1113.3765091548757;20738.688919666216;21525.13899444036;21635.459113522287;21730.348089557338;21819.127234910426;1037.4190453346;1066.7279843639822;1068.257560995101;1071.8124328242527;1082.118073769875;11269.774604507864;12243.877561224452;12267.509299627915;12458.501659933501;12499.187552809039;549418;549899;550033;562028;573303;65142.34857651363;65955.67022164955;69451;90271;103666.48166759119;1041.2138835179542;1089.3844977587637;1094.272308914563;1097.5006372100675;1102.2339474065448;18738.344157954405;18803.717944230575;18882.52793680114;19213.711794323375;19339.806601934364;45411.545884540734;45435.77282113568;45878.31182532198;46125.53874461347;46420.30369544527;63658.68170659117;64453.35546644474;64774.35225647872;66031.33968660251;67337;8809.911900881165;8866.86699699492;8927.508987005518;8950.34506814146;9130.543472826199;9310.441373517611;10109.39343639384;13285.468581256642;14106.858931410554;15012.6997460048;12243.510259589517;12325.691857703629;12494.750104997667;12693.238405695687;12798.232106073665;12062.095342849147;12144.453499592717;12260.203086799329;12584.119111662096;12741.426635801596;12188.817167742669;12604.432800523815;12879.098463107492;12943.741125177632;13055.738885222432;8789.824203516022;8874;8942.50816205115;8959.73120806377;9102.681406150752;9430.858537121812;12018.158728888893;12312.630621081382;12629.74740505203;13196.538121166097 13:43:39 INFO - PROCESS | 1930 | |3;mootools.html;1550.6200980759716;1598.3926108078929;1785.401890366727;1821.107657247949;1879.0698604190918;9318.114779095991;9959.302848800588;9974.501274936252;9974.551145198457;9988.600455981752;8419.32645388369;8648.091950345202;8651.610677519511;8855.158759917798;9778.951105244463;2327.4879526504183;2449.951000979979;2458.815588830836;2458.9016439342404;2469.4196863737006;2434.1480481831304;2436.196055301746;2467.4571594249237;2491.6885389326335;2496.987515062422;2454.7422520635346;2470.5429495543294;2490.950180996379;2495.189063554343;2515.861627610481;2481.3176376496426;2485.9129930452345;2498.750124987505;2507.3104896153523;2513.635522849195;2364.2552595932225;2382.1305223593436;2383.2016274548046;2387.0929046962215;2387.7254115776736;9467.66863159793;9521.857172142423;9531.618735250651;9545.52272386378;9546.093121153479;5538.833834984957;5718.256626638552;5727.684977326246;5729.4843464088435;5730.283714535683;3527.4708793680898;3584.5409180141028;3722.590515043343;3724.491580909709;3730.123420996064;1188.4295538141655;1220.3837062283578;1228.9508419663202;1231.4889320931802;1241.1994263699953;13465.461381544726;13589.048766586342;13668.863311366858;13677.589672309747;13684.863151368458;4912.656114072052;5007.749612519359;5155.32980712505;5174.844754657366;5190.221466779938;11647.6505704829;11651.941740291244;11679.416029198506;11693.766124677459;11698.06415486759;5732.656040637492;5812.244408226903;5824;5824.126381042793;5849.151872978479;5146.099432599355;5223.869403264873;5224.869378265497;5229.398619158769;5343.305370301836;4217.219814334358;4236.025714085716;4252.107057517956;4347.5652434756275;4361;1317.2952470428274;1360.3130419138272;1376.2499437806418;1385.7020740540831;1388.187910072619;1023.8208313545249;1060.3637817309552;1071.7481391872905;1074.4573990134932;1087.4508373271447;6256.812295631138;6271.7804876830005;6618.205815302098;6624.834379140464;6697.229818570927;495.17306098815067;502.87176769923434;1107.4573459005192;1125.178619607691;1141.9200655954169;4286.37847512109;4616.746078965622;4683.1336202802595;4767.974885399656;4788.7366194859615;11673.941630291964;12208.572699955459;12289.56986505468;12346.444410001392;12376.690582735504;12061.276323420447;12245.571405000783;12288.016958643288;12312.569060082855;12322.81515777264;8695.521746304014;8804.515751633593;8817.867731984026;8825.867611985825;8828.07305232954;6247.250329960252;6363.6818159090935;6460.25707043696;6473.093766872736;6510.121133646897;10694.73263168427;10750.13998880072;10784.946075269887;10786.568537258421;10807.513661885077;10744.51649675751;10914.617988370368;11043.282186657842;11304.49129789145;11313.604023859125;8305.211004954519;8429.747107586782;8503.617337219715;8519.190676885686;8526.531040792568;9412.294077944045;9432.103950124727;9510.619575216913;9532.475713835805;9536.141747242782;8269.131741167092;8328.875066873881;8547.572621368783;8610.65557377698;8796.340274479311;7671.232876712284;7759.534427934342;7780.727674531364;7786.7663970080985;7867.84264314722;7870.212978702084;7884.132745398117;7950.045994480699;7952.284294413531;7989.121196668478;5893.823185304448;5950.583459157818;6009.579329446897;6065.272167339947;6093.9085913712215;5662.377138514842;5845.970770146122;5863.501602363847;5929.822105336847;6129.049997250433 13:43:39 INFO - PROCESS | 1930 | |4;prototype.html;348.18872028174354;352.0758010223164;357.7781775299314;361.2756423371144;365.4810169559227;5511.5039646431815;5535.640183388077;5540.806071787489;5558.638688485251;5612.943870561293;5498.890022199554;5518.075420027392;5521.309836270466;5525.784327447965;5538.9169162462595;3873.4383514390397;4119.642491369532;4246.02341461464;4334.956650433503;4359.455068116486;4341.9782901085455;4343.5004974427875;4347.456567929009;4373.606375426211;4393.692441529094;4175.68682348825;4330.307150855863;4339.153864996327;4380.386745855582;4381.583749543788;4095.344744840841;4300.440942677463;4319.589638984291;4356.520782713899;4372.169287835333;3872.3610604250266;3875.147467557133;3880.3403421418425;3887.436321733333;3909.902252443683;5246.140853234996;5367.463253674601;5398.109311963561;5404.729763511848;5432.728363581805;4917.0903382874285;4927.408710954673;4927.90144197114;4961;4962.429320628102;5681.892031053946;5683.857903552403;5696.487316141525;5706.3152421709665;5814.767409303623;4115.382692596105;4264.658827293809;4276.358546218061;4289.871303860889;4295.72077814941;5596.916046259309;5835.737391817378;5936.693927336022;5948.672822994734;5949.762009519614;4345.717528360647;4353.10761293934;4358.782060897006;4376.562343765598;4387.517373088957;4426.291793313118;4426.557344265548;4432.800523976429;4435.800388982503;4441.889527618096;4858.489858564871;4972.353594032753;4982.676126051795;4989.850304490871;5001.7499125043605;5674.744636491368;5735.512481439125;5755.366909639936;5756.683382414008;5757.825576457035;1501.1893577468045;1513.3719506404939;1513.5610672904734;1525.7101150781316;1539.2303848075962;938.5354249646468;975.7316737896994;985.9795112059128;990.5493000684671;1003.6186249225248;4918.5819205367225;4930.852074437773;4961.875953101201;4964.8758781030765;5003.473940448125;5182.170852663556;5191.987562425366;5205.687658740488;5217.3739251220795;5226.790928362823;4966.90066198674;4983.202687570044;4985.177445721488;5259.447757985433;5703.201551782754;3141.858616362269;3191.0586377018835;3198.856051477689;3201.4717571600427;3208.502682084281;6037.969810151009;6147.723352449061;6207.472364848948;6222.253329600387;6239.719212635442;6020;6058.7882423515475;6092.360302168208;6095.7866474674065;6115.969420152871;6115.938840611626;6171.932402645164;6184.567080304336;6194.5973511721595;6202.7208775606105;5847.5906686533235;5932.970335148297;5982.192404025401;5985.25184351956;6015.789447369321;5928.3775203603;5937.851553711192;5980.252468441445;5987.2815262168815;5991.2510936132985;5889.764409423575;5943.435373639497;6105.542084343603;6137.526993521612;6137.907931380943;4223.134257477272;4237.427947227085;4266.466691663542;4276.358546217968;4282.764447955392;5801.970990145022;6017.488513476404;6032.245969253843;6045.969770151297;6055.515558755201;5810.738516766671;5839.854003649943;5849.795257165979;5868.119782032729;5879.235699359056;5846.532277417711;5849.853753656193;5851.074462768615;5852.502537284208;5870.119482077552;5598.285711444621;5686.09022556389;5782.306123265235;5788.797392091256;5828.076734677462;5902.498287645598;5903.173555702119;5932.347441781314;5934.821955341346;5941.821745347646;5466.01611709896;5680.40948534411;5735.627184233012;5753.252077229933;5772.393898640582 13:43:39 INFO - PROCESS | 1930 | |5;yui.html;730.7880714592771;739.7299985505292;792.3265224559126;875.5578432891389;924.4197579845269;16582.170891455426;16871.24079416426;17775.73336399955;17850.928944263338;18293.817061829377;17132.571685707862;17840.732389014174;17968;17983.460496185104;17987.280508779666;863.7667829685981;891.3270480786997;896.9820603587924;897.3270047464401;898.1198425542972;742.2503271695599;860.849351363512;877.0527829943677;881.5460038080394;881.7839629290801;926.5043201886958;929.8977112517617;930.4045410937006;930.5161316115616;930.9255259579218;923.5936188077225;923.6674797073082;923.8290916180495;924.2421214604027;925.3383830561147;560.9102543593006;652.5921299188008;665.5574043261225;669.9464042876558;674.6795272245693;16012.359505619761;16047.197640117947;16060.11669358185;16068.116253606047;16069.357225710957;15482.922585387001;15725.92137039319;15816.52550423489;16050.5987350316;16284.674306513803;13339.666508337272;13428.19430834153;13608;14345.856541434661;14831.258437078102;4200.495940487131;4236.5128010278595;4300.311950088002;4323.286657701507;4350.608445239912;65136.67431662859;65490;69189.65405172942;78193;79136.20863791346;12340.197887137307;12420.192687475284;12591.307478088793;12603.558875439316;12761.553345633043;21648.567028659338;21879.12483500642;21881.234156804436;21911.342659720234;22045.669314960287;8749.693760718344;8760.4305720128;9043.68347107848;9084.591193396313;9500.809983800285;5096.286519887219;5344.251804747339;5395.81114660985;5475.397706252309;5482.17767334903;3912.158885839558;4071.1654110907784;4075.083106301118;4080.3063479208604;4081.469408976873;664.9368310010541;674.8279188806921;679.2256827216977;689.2349492063805;692.0933577014127;796.8485987662442;828.411827602397;842.1957031035347;845.8815765792793;882.2148288023664;10516;10697;10862.402567858844;11125.888741112485;11540.884591153981;764.1746913333586;779.8284377436955;805.2068712318362;814.1817473439274;839.7029810942887;5091.338126043591;5202.3497062867145;5204.921926171035;5279.815206467755;5294.258803767476;82982.5850870754;120862;130865.34567327102;139868;143481;111932;114009;114272;116544.41727791307;116898;76415.61792191115;78809;80067;101595.49202254089;104853;10372;10567.193567795195;10689.89310106889;10832.67501974942;11210.66368008961;57955.42044579669;58122.418775811704;58637.70681146567;59251.40748592631;61197;47270;51354.74322628363;51533.48466515287;51690;73630.63184684256;13902.721945561234;14242.501512447428;14567.854321456649;14872.851271487147;15064.171470569225;15798;16569.66860662804;16792.916035419745;17324.047177405362;18083.909580452015;15375.154366509501;15570.14364209934;15756.054636721834;15801.209939502749;15907.363705451653;14822.555323340317;15399.3070311834;15563.688726225639;16137.75793363124;16145.1927403627;13495.055346125677;13951.093693765763;14149.504767333094;14344.354504047551;14766.48317308932;11633.883661163618;12128.332941688292;12218.144729868824;12596.055295852664;12879.613611591667;11821.527138914691;11945.82081268799;12186.51253949832;12350.505979760708;12680.429380678077 13:43:39 INFO - PROCESS | 1930 | __end_tp_report 13:43:39 INFO - PROCESS | 1930 | __start_cc_report 13:43:39 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1089 13:43:39 INFO - PROCESS | 1930 | __end_cc_report 13:43:39 INFO - PROCESS | 1930 | __startTimestamp1472157819386__endTimestamp 13:43:39 INFO - PROCESS | 1930 | 13:43:39 INFO - PROCESS | 1930 | ------- Summary: start ------- 13:43:39 INFO - PROCESS | 1930 | Number of tests: 6 13:43:39 INFO - PROCESS | 1930 | 13:43:39 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5721.27 Median:5970.94 stddev:665.99 (11.2%) stddev-sans-first:638.64 13:43:39 INFO - PROCESS | 1930 | Values: 3226.9 3237.8 3253.9 3264.2 3270.6 5608.7 5712.7 5713.3 5721.7 5734.5 5675.7 5810.4 5820.7 5830.2 5835.3 5125.5 5238.2 5267.6 5282.4 5306.4 5142.8 5250.0 5288.9 5296.5 5314.6 5275.6 5301.9 5315.3 5318.3 5343.1 5377.2 5396.9 5398.8 5406.2 5406.8 5128.9 5130.2 5181.8 5293.7 5304.7 5561.1 5598.4 5634.8 5642.4 5674.9 6107.3 6123.8 6125.3 6125.4 6126.4 6141.0 6142.8 6145.3 6159.9 6225.9 5803.1 5842.5 5849.0 5852.2 5855.7 6116.6 6359.8 6364.1 6369.2 6370.8 6000.1 6067.6 6069.5 6113.1 6117.8 6218.3 6223.0 6224.9 6227.0 6231.7 5909.4 6184.1 6185.1 6201.0 6209.1 5931.3 5975.1 5994.4 6026.1 6057.8 4583.4 4612.5 4617.1 4621.2 4645.7 4082.9 4111.4 4116.5 4118.2 4125.3 5737.3 5776.2 5797.4 5815.0 5826.3 6148.9 6152.9 6157.0 6157.3 6160.1 5750.7 5783.4 5814.0 5820.4 5837.9 5284.2 5346.2 5352.4 5372.1 5374.5 6374.6 6415.9 6426.0 6426.5 6432.0 6046.5 6055.5 6235.9 6242.3 6263.1 6265.2 6295.6 6321.9 6329.4 6330.9 6008.9 6035.9 6036.3 6048.9 6049.0 5963.5 6147.1 6155.5 6167.2 6177.1 6121.9 6131.9 6132.7 6134.9 6150.6 6035.9 6076.2 6084.8 6085.4 6094.0 5831.5 5937.5 6033.0 6039.7 6053.3 6030.5 6117.8 6128.6 6130.4 6132.3 5962.3 5969.5 5970.9 5974.3 6008.2 13:43:39 INFO - PROCESS | 1930 | 13:43:39 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:66970.10 Median:39986.30 stddev:133739.07 (334.5%) stddev-sans-first:134021.42 13:43:39 INFO - PROCESS | 1930 | Values: 603.9 616.8 646.5 660.6 663.2 36338.3 47772.5 48860.8 48970.5 51333.0 47438.0 47790.5 47885.5 47896.3 47943.3 8533.5 9331.1 9484.8 9494.7 11117.7 10907.5 11419.4 11420.4 11427.4 11429.9 11466.8 11512.2 11527.5 11530.7 11532.3 11360.4 11388.7 11434.3 11459.0 11465.6 11243.7 11294.3 11308.2 11323.5 11325.3 46975.1 47262.8 47321.8 47383.8 47408.5 3944.7 3966.6 4579.4 4705.7 4754.1 34856.3 35338.8 35399.6 35598.8 37089.6 4687.3 4701.0 4757.4 4760.2 4835.0 377800.0 379100.0 379613.0 380302.0 382447.0 67926.7 68290.3 68294.0 68438.3 68467.7 40301.4 40883.0 41086.4 71555.0 71763.6 19916.6 20017.6 20030.1 20036.8 20170.8 13717.2 13967.0 14043.4 14063.9 15389.3 21606.4 21690.8 21862.5 21970.9 21978.1 7435.0 7470.4 7623.3 7624.8 7905.2 1041.5 1091.0 1120.4 1135.4 1158.9 34652.7 34815.3 35117.8 35196.5 35355.0 1140.7 1153.6 1200.4 1236.4 1297.5 18711.0 18745.2 18910.3 18940.3 18995.3 628557.0 634294.0 636063.0 636939.0 640313.0 65454.3 66004.3 66124.3 66125.7 66149.3 1357.8 1434.2 1479.3 1483.4 1500.8 33444.0 35499.6 35751.3 35906.5 35917.3 56222.7 56762.0 56769.0 56850.0 56955.0 459053.0 462021.0 462657.0 462881.0 463928.0 30526.0 31207.7 31747.8 33019.5 33221.5 52304.7 52413.5 53112.0 53116.7 53412.7 43444.5 44901.0 45295.5 45609.8 47085.8 44712.0 45853.3 45963.5 46442.1 46534.3 44634.0 45359.0 45985.1 46160.0 46528.0 38395.0 39909.8 40062.8 40555.6 40908.0 28518.7 29014.3 29045.9 29178.0 29254.6 13:43:39 INFO - PROCESS | 1930 | 13:43:39 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:31011.80 Median:24610.32 stddev:91104.50 (370.2%) stddev-sans-first:91331.08 13:43:39 INFO - PROCESS | 1930 | Values: 401.5 413.3 429.2 431.4 437.6 15269.0 19987.1 20155.8 20708.6 20863.4 20236.8 20287.2 20305.0 20354.1 20537.7 5795.5 5855.1 5864.3 5899.6 5906.8 5786.0 5839.0 5851.1 5852.3 5853.6 5733.5 5735.1 5755.9 5791.2 5878.2 5562.5 5843.8 5857.6 5865.0 5865.4 5146.1 5278.0 5299.1 5300.7 5303.2 24158.0 24298.9 24393.6 24516.4 24704.3 2653.5 2666.4 2747.5 3070.5 3343.3 15254.4 15450.7 15498.5 15500.9 15513.0 2060.8 2108.2 2120.0 2135.6 2192.4 54256.0 58204.7 65899.3 67518.7 80556.0 11234.6 11334.3 11452.1 11592.9 11709.8 16079.4 16114.9 16187.8 16190.6 16203.9 9095.9 9122.9 9136.3 9191.6 9358.7 8802.7 9053.1 9066.6 9211.4 9256.4 7047.5 7134.5 7146.6 7169.2 7233.4 1980.2 1985.6 1989.4 2096.7 2112.6 932.8 996.1 1009.9 1025.1 1113.4 20738.7 21525.1 21635.5 21730.3 21819.1 1037.4 1066.7 1068.3 1071.8 1082.1 11269.8 12243.9 12267.5 12458.5 12499.2 549418.0 549899.0 550033.0 562028.0 573303.0 65142.3 65955.7 69451.0 90271.0 103666.5 1041.2 1089.4 1094.3 1097.5 1102.2 18738.3 18803.7 18882.5 19213.7 19339.8 45411.5 45435.8 45878.3 46125.5 46420.3 63658.7 64453.4 64774.4 66031.3 67337.0 8809.9 8866.9 8927.5 8950.3 9130.5 9310.4 10109.4 13285.5 14106.9 15012.7 12243.5 12325.7 12494.8 12693.2 12798.2 12062.1 12144.5 12260.2 12584.1 12741.4 12188.8 12604.4 12879.1 12943.7 13055.7 8789.8 8874.0 8942.5 8959.7 9102.7 9430.9 12018.2 12312.6 12629.7 13196.5 13:43:39 INFO - PROCESS | 1930 | 13:43:39 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:6345.18 Median:4725.55 stddev:3643.39 (77.1%) stddev-sans-first:3635.79 13:43:39 INFO - PROCESS | 1930 | Values: 1550.6 1598.4 1785.4 1821.1 1879.1 9318.1 9959.3 9974.5 9974.6 9988.6 8419.3 8648.1 8651.6 8855.2 9779.0 2327.5 2450.0 2458.8 2458.9 2469.4 2434.1 2436.2 2467.5 2491.7 2497.0 2454.7 2470.5 2491.0 2495.2 2515.9 2481.3 2485.9 2498.8 2507.3 2513.6 2364.3 2382.1 2383.2 2387.1 2387.7 9467.7 9521.9 9531.6 9545.5 9546.1 5538.8 5718.3 5727.7 5729.5 5730.3 3527.5 3584.5 3722.6 3724.5 3730.1 1188.4 1220.4 1229.0 1231.5 1241.2 13465.5 13589.0 13668.9 13677.6 13684.9 4912.7 5007.7 5155.3 5174.8 5190.2 11647.7 11651.9 11679.4 11693.8 11698.1 5732.7 5812.2 5824.0 5824.1 5849.2 5146.1 5223.9 5224.9 5229.4 5343.3 4217.2 4236.0 4252.1 4347.6 4361.0 1317.3 1360.3 1376.2 1385.7 1388.2 1023.8 1060.4 1071.7 1074.5 1087.5 6256.8 6271.8 6618.2 6624.8 6697.2 495.2 502.9 1107.5 1125.2 1141.9 4286.4 4616.7 4683.1 4768.0 4788.7 11673.9 12208.6 12289.6 12346.4 12376.7 12061.3 12245.6 12288.0 12312.6 12322.8 8695.5 8804.5 8817.9 8825.9 8828.1 6247.3 6363.7 6460.3 6473.1 6510.1 10694.7 10750.1 10784.9 10786.6 10807.5 10744.5 10914.6 11043.3 11304.5 11313.6 8305.2 8429.7 8503.6 8519.2 8526.5 9412.3 9432.1 9510.6 9532.5 9536.1 8269.1 8328.9 8547.6 8610.7 8796.3 7671.2 7759.5 7780.7 7786.8 7867.8 7870.2 7884.1 7950.0 7952.3 7989.1 5893.8 5950.6 6009.6 6065.3 6093.9 5662.4 5846.0 5863.5 5929.8 6129.0 13:43:39 INFO - PROCESS | 1930 | 13:43:39 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4855.39 Median:5482.45 stddev:1412.46 (25.8%) stddev-sans-first:1375.31 13:43:39 INFO - PROCESS | 1930 | Values: 348.2 352.1 357.8 361.3 365.5 5511.5 5535.6 5540.8 5558.6 5612.9 5498.9 5518.1 5521.3 5525.8 5538.9 3873.4 4119.6 4246.0 4335.0 4359.5 4342.0 4343.5 4347.5 4373.6 4393.7 4175.7 4330.3 4339.2 4380.4 4381.6 4095.3 4300.4 4319.6 4356.5 4372.2 3872.4 3875.1 3880.3 3887.4 3909.9 5246.1 5367.5 5398.1 5404.7 5432.7 4917.1 4927.4 4927.9 4961.0 4962.4 5681.9 5683.9 5696.5 5706.3 5814.8 4115.4 4264.7 4276.4 4289.9 4295.7 5596.9 5835.7 5936.7 5948.7 5949.8 4345.7 4353.1 4358.8 4376.6 4387.5 4426.3 4426.6 4432.8 4435.8 4441.9 4858.5 4972.4 4982.7 4989.9 5001.7 5674.7 5735.5 5755.4 5756.7 5757.8 1501.2 1513.4 1513.6 1525.7 1539.2 938.5 975.7 986.0 990.5 1003.6 4918.6 4930.9 4961.9 4964.9 5003.5 5182.2 5192.0 5205.7 5217.4 5226.8 4966.9 4983.2 4985.2 5259.4 5703.2 3141.9 3191.1 3198.9 3201.5 3208.5 6038.0 6147.7 6207.5 6222.3 6239.7 6020.0 6058.8 6092.4 6095.8 6116.0 6115.9 6171.9 6184.6 6194.6 6202.7 5847.6 5933.0 5982.2 5985.3 6015.8 5928.4 5937.9 5980.3 5987.3 5991.3 5889.8 5943.4 6105.5 6137.5 6137.9 4223.1 4237.4 4266.5 4276.4 4282.8 5802.0 6017.5 6032.2 6046.0 6055.5 5810.7 5839.9 5849.8 5868.1 5879.2 5846.5 5849.9 5851.1 5852.5 5870.1 5598.3 5686.1 5782.3 5788.8 5828.1 5902.5 5903.2 5932.3 5934.8 5941.8 5466.0 5680.4 5735.6 5753.3 5772.4 13:43:39 INFO - PROCESS | 1930 | 13:43:39 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:21836.24 Median:12978.91 stddev:31411.38 (242.0%) stddev-sans-first:31459.52 13:43:39 INFO - PROCESS | 1930 | Values: 730.8 739.7 792.3 875.6 924.4 16582.2 16871.2 17775.7 17850.9 18293.8 17132.6 17840.7 17968.0 17983.5 17987.3 863.8 891.3 897.0 897.3 898.1 742.3 860.8 877.1 881.5 881.8 926.5 929.9 930.4 930.5 930.9 923.6 923.7 923.8 924.2 925.3 560.9 652.6 665.6 669.9 674.7 16012.4 16047.2 16060.1 16068.1 16069.4 15482.9 15725.9 15816.5 16050.6 16284.7 13339.7 13428.2 13608.0 14345.9 14831.3 4200.5 4236.5 4300.3 4323.3 4350.6 65136.7 65490.0 69189.7 78193.0 79136.2 12340.2 12420.2 12591.3 12603.6 12761.6 21648.6 21879.1 21881.2 21911.3 22045.7 8749.7 8760.4 9043.7 9084.6 9500.8 5096.3 5344.3 5395.8 5475.4 5482.2 3912.2 4071.2 4075.1 4080.3 4081.5 664.9 674.8 679.2 689.2 692.1 796.8 828.4 842.2 845.9 882.2 10516.0 10697.0 10862.4 11125.9 11540.9 764.2 779.8 805.2 814.2 839.7 5091.3 5202.3 5204.9 5279.8 5294.3 82982.6 120862.0 130865.3 139868.0 143481.0 111932.0 114009.0 114272.0 116544.4 116898.0 76415.6 78809.0 80067.0 101595.5 104853.0 10372.0 10567.2 10689.9 10832.7 11210.7 57955.4 58122.4 58637.7 59251.4 61197.0 47270.0 51354.7 51533.5 51690.0 73630.6 13902.7 14242.5 14567.9 14872.9 15064.2 15798.0 16569.7 16792.9 17324.0 18083.9 15375.2 15570.1 15756.1 15801.2 15907.4 14822.6 15399.3 15563.7 16137.8 16145.2 13495.1 13951.1 14149.5 14344.4 14766.5 11633.9 12128.3 12218.1 12596.1 12879.6 11821.5 11945.8 12186.5 12350.5 12680.4 13:43:39 INFO - PROCESS | 1930 | -------- Summary: end -------- 13:43:39 INFO - PROCESS | 1930 | 13:43:40 INFO - TEST-INFO | 1930: exit 0 13:43:40 INFO - TEST-OK | dromaeo_css | took 1112205ms 13:43:40 INFO - TEST-START | kraken 13:43:40 INFO - operating with platform_type : linux_ 13:43:40 INFO - Initialising browser for kraken test... 13:43:40 INFO - TEST-INFO | started process 2132 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1GvlnL/profile http://localhost:46109/getInfo.html) 13:43:43 INFO - PROCESS | 2132 | __metrics Screen width/height:1600/1200 13:43:43 INFO - PROCESS | 2132 | colorDepth:24 13:43:43 INFO - PROCESS | 2132 | Browser inner width/height: 1024/697 13:43:43 INFO - PROCESS | 2132 | __metrics 13:43:44 INFO - PROCESS | 2132 | 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" 13:43:44 INFO - PROCESS | 2132 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:43:45 INFO - TEST-INFO | 2132: exit 0 13:43:45 INFO - Browser initialized. 13:43:45 INFO - Running cycle 1/1 for kraken test... 13:43:45 INFO - TEST-INFO | started process 2218 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1GvlnL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:43:56 INFO - PROCESS | 2218 | RSS: Main: 145084416 13:43:56 INFO - PROCESS | 2218 | 13:43:59 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46109/tests/kraken/driver.html?testName=audio-beat-detection) 13:44:00 INFO - PROCESS | 2218 | RSS: Main: 191590400 13:44:00 INFO - PROCESS | 2218 | 13:44:02 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46109/tests/kraken/driver.html?testName=audio-dft) 13:44:03 INFO - PROCESS | 2218 | RSS: Main: 182161408 13:44:03 INFO - PROCESS | 2218 | 13:44:06 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46109/tests/kraken/driver.html?testName=audio-fft) 13:44:06 INFO - PROCESS | 2218 | RSS: Main: 271163392 13:44:06 INFO - PROCESS | 2218 | 13:44:08 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46109/tests/kraken/driver.html?testName=audio-oscillator) 13:44:09 INFO - PROCESS | 2218 | RSS: Main: 175849472 13:44:09 INFO - PROCESS | 2218 | 13:44:11 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46109/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:44:12 INFO - PROCESS | 2218 | RSS: Main: 177668096 13:44:12 INFO - PROCESS | 2218 | 13:44:15 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46109/tests/kraken/driver.html?testName=imaging-darkroom) 13:44:15 INFO - PROCESS | 2218 | RSS: Main: 213331968 13:44:15 INFO - PROCESS | 2218 | 13:44:19 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46109/tests/kraken/driver.html?testName=imaging-desaturate) 13:44:19 INFO - PROCESS | 2218 | RSS: Main: 212336640 13:44:19 INFO - PROCESS | 2218 | 13:44:22 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46109/tests/kraken/driver.html?testName=json-parse-financial) 13:44:23 INFO - PROCESS | 2218 | RSS: Main: 211763200 13:44:23 INFO - PROCESS | 2218 | 13:44:24 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46109/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:44:25 INFO - PROCESS | 2218 | RSS: Main: 176099328 13:44:25 INFO - PROCESS | 2218 | 13:44:26 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46109/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:44:27 INFO - PROCESS | 2218 | RSS: Main: 183447552 13:44:27 INFO - PROCESS | 2218 | 13:44:29 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46109/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:44:30 INFO - PROCESS | 2218 | RSS: Main: 186478592 13:44:30 INFO - PROCESS | 2218 | 13:44:32 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:44:33 INFO - PROCESS | 2218 | RSS: Main: 181477376 13:44:33 INFO - PROCESS | 2218 | 13:44:35 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:44:36 INFO - PROCESS | 2218 | RSS: Main: 184291328 13:44:36 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | Cycle 1(1): loaded http://localhost:46109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46109/tests/kraken/driver.html?testName=ai-astar) 13:44:38 INFO - PROCESS | 2218 | RSS: Main: 235061248 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | __start_tp_report 13:44:38 INFO - PROCESS | 2218 | _x_x_mozilla_page_load 13:44:38 INFO - PROCESS | 2218 | _x_x_mozilla_page_load_details 13:44:38 INFO - PROCESS | 2218 | |i|pagename|runs| 13:44:38 INFO - PROCESS | 2218 | |0;ai-astar;101;112;110;110;113;113;112;111;110;110 13:44:38 INFO - PROCESS | 2218 | |1;audio-beat-detection;145;115;118;176;116;119;149;116;156;116 13:44:38 INFO - PROCESS | 2218 | |2;audio-dft;161;160;161;160;159;160;160;162;161;162 13:44:38 INFO - PROCESS | 2218 | |3;audio-fft;75;75;75;107;76;76;75;79;98;74 13:44:38 INFO - PROCESS | 2218 | |4;audio-oscillator;84;84;134;83;82;82;110;83;84;125 13:44:38 INFO - PROCESS | 2218 | |5;imaging-gaussian-blur;116;117;117;117;116;116;116;117;117;117 13:44:38 INFO - PROCESS | 2218 | |6;imaging-darkroom;145;145;146;146;146;145;145;146;146;145 13:44:38 INFO - PROCESS | 2218 | |7;imaging-desaturate;95;94;95;95;94;95;95;95;94;95 13:44:38 INFO - PROCESS | 2218 | |8;json-parse-financial;51;50;49;51;50;49;50;51;49;49 13:44:38 INFO - PROCESS | 2218 | |9;json-stringify-tinderbox;62;61;60;59;60;61;61;60;61;61 13:44:38 INFO - PROCESS | 2218 | |10;stanford-crypto-aes;76;77;72;78;72;72;72;81;78;72 13:44:38 INFO - PROCESS | 2218 | |11;stanford-crypto-ccm;121;128;116;117;125;125;116;127;157;120 13:44:38 INFO - PROCESS | 2218 | |12;stanford-crypto-pbkdf2;157;152;155;155;158;154;158;152;159;154 13:44:38 INFO - PROCESS | 2218 | |13;stanford-crypto-sha256-iterative;62;62;62;61;60;63;62;61;60;61 13:44:38 INFO - PROCESS | 2218 | __end_tp_report 13:44:38 INFO - PROCESS | 2218 | __start_cc_report 13:44:38 INFO - PROCESS | 2218 | _x_x_mozilla_cycle_collect,1664 13:44:38 INFO - PROCESS | 2218 | __end_cc_report 13:44:38 INFO - PROCESS | 2218 | __startTimestamp1472157878449__endTimestamp 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | ------- Summary: start ------- 13:44:38 INFO - PROCESS | 2218 | Number of tests: 14 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.50 stddev:3.46 (3.1%) stddev-sans-first:1.30 13:44:38 INFO - PROCESS | 2218 | Values: 101.0 112.0 110.0 110.0 113.0 113.0 112.0 111.0 110.0 110.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:132.00 stddev:22.08 (16.7%) stddev-sans-first:22.96 13:44:38 INFO - PROCESS | 2218 | Values: 145.0 115.0 118.0 176.0 116.0 119.0 149.0 116.0 156.0 116.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 13:44:38 INFO - PROCESS | 2218 | Values: 161.0 160.0 161.0 160.0 159.0 160.0 160.0 162.0 161.0 162.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.50 stddev:11.60 (15.4%) stddev-sans-first:12.10 13:44:38 INFO - PROCESS | 2218 | Values: 75.0 75.0 75.0 107.0 76.0 76.0 75.0 79.0 98.0 74.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:83.00 stddev:20.10 (24.2%) stddev-sans-first:20.91 13:44:38 INFO - PROCESS | 2218 | Values: 84.0 84.0 134.0 83.0 82.0 82.0 110.0 83.0 84.0 125.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 13:44:38 INFO - PROCESS | 2218 | Values: 116.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 13:44:38 INFO - PROCESS | 2218 | Values: 145.0 145.0 146.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 13:44:38 INFO - PROCESS | 2218 | Values: 95.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:0.88 (1.8%) stddev-sans-first:0.83 13:44:38 INFO - PROCESS | 2218 | Values: 51.0 50.0 49.0 51.0 50.0 49.0 50.0 51.0 49.0 49.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.84 (1.4%) stddev-sans-first:0.73 13:44:38 INFO - PROCESS | 2218 | Values: 62.0 61.0 60.0 59.0 60.0 61.0 61.0 60.0 61.0 61.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:76.50 stddev:3.40 (4.4%) stddev-sans-first:3.59 13:44:38 INFO - PROCESS | 2218 | Values: 76.0 77.0 72.0 78.0 72.0 72.0 72.0 81.0 78.0 72.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:125.00 stddev:12.04 (9.6%) stddev-sans-first:12.67 13:44:38 INFO - PROCESS | 2218 | Values: 121.0 128.0 116.0 117.0 125.0 125.0 116.0 127.0 157.0 120.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.40 Median:156.00 stddev:2.50 (1.6%) stddev-sans-first:2.59 13:44:38 INFO - PROCESS | 2218 | Values: 157.0 152.0 155.0 155.0 158.0 154.0 158.0 152.0 159.0 154.0 13:44:38 INFO - PROCESS | 2218 | 13:44:38 INFO - PROCESS | 2218 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:62.00 stddev:0.97 (1.6%) stddev-sans-first:1.00 13:44:38 INFO - PROCESS | 2218 | Values: 62.0 62.0 62.0 61.0 60.0 63.0 62.0 61.0 60.0 61.0 13:44:38 INFO - PROCESS | 2218 | -------- Summary: end -------- 13:44:38 INFO - PROCESS | 2218 | 13:44:39 INFO - TEST-INFO | 2218: exit 0 13:44:39 INFO - TEST-OK | kraken | took 59316ms 13:44:39 INFO - SUITE-END | took 1171s 13:44:39 INFO - Completed test suite (00:19:31) 13:44:39 INFO - javascript benchmark 13:44:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3226.9193270168244, 3237.8219197944113, 3253.8535765890524, 3264.232905267263, 3270.640229574748, 5608.691521966291, 5712.685802280873, 5713.257276554053, 5721.742521586528, 5734.455226753452, 5675.716214189295, 5810.448007439287, 5820.650760954346, 5830.154627578999, 5835.270591176103, 5125.538701516863, 5238.214267859814, 5267.578593712513, 5282.445343238953, 5306.363236411636, 5142.768575414116, 5250.028744682246, 5288.947110528884, 5296.523312901838, 5314.601404894648, 5275.60432967529, 5301.867453313659, 5315.335583052119, 5318.282031925679, 5343.091674415339, 5377.247185394048, 5396.865078373033, 5398.811041613564, 5406.216098665672, 5406.756695948692, 5128.871778205575, 5130.153524668425, 5181.766820493087, 5293.682379057231, 5304.655197412156, 5561.110222364443, 5598.356189038232, 5634.774609015634, 5642.407547207526, 5674.858128546819, 6107.267127944675, 6123.846903827395, 6125.264968203799, 6125.448709616111, 6126.417990290915, 6141.0, 6142.836439233798, 6145.293291271516, 6159.87680246397, 6225.87548249037, 5803.129530570406, 5842.4741773240185, 5849.0, 5852.180694702745, 5855.736491857877, 6116.633002019893, 6359.841003974938, 6364.077208804697, 6369.172007638977, 6370.8088757337355, 6000.069989151689, 6067.635941843503, 6069.514438844882, 6113.0891616662275, 6117.785877494355, 6218.284897236786, 6223.004319308889, 6224.906626400608, 6227.0, 6231.688415579203, 5909.379515150933, 6184.134221209034, 6185.134081228632, 6201.038838979875, 6209.0996805462955, 5931.347551769302, 5975.073863551156, 5994.430529099728, 6026.126211699279, 6057.818265452043, 4583.381243532081, 4612.53874612536, 4617.053504031665, 4621.214393553104, 4645.721256724607, 4082.897927551835, 4111.424400583921, 4116.485439320085, 4118.155778065489, 4125.319322311845, 5737.340205876294, 5776.191333213354, 5797.420257974253, 5815.0, 5826.300843898674, 6148.938510614926, 6152.907706384408, 6156.969215153896, 6157.261128664589, 6160.1375807387, 5750.654960702372, 5783.421657834183, 5813.9534883721335, 5820.35976042635, 5837.88324233513, 5284.154535274338, 5346.224797404355, 5352.357717073976, 5372.113601255827, 5374.516293533601, 6374.553781235361, 6415.871682566416, 6426.0, 6426.485881129498, 6431.967840160769, 6046.455818976137, 6055.485283750754, 6235.937640623717, 6242.344553821783, 6263.091851681572, 6265.248170219603, 6295.559310848196, 6321.873562528632, 6329.430351268408, 6330.936690633035, 6008.939910601013, 6035.9094613579955, 6036.336003039574, 6048.879022419615, 6048.969755151372, 5963.493103086286, 6147.108669242845, 6155.47678447337, 6167.198264225622, 6177.073438984188, 6121.910283036266, 6131.908021379772, 6132.661461321519, 6134.877302454015, 6150.60021098645, 6035.9094613579955, 6076.240469941257, 6084.847878803065, 6085.391460853879, 6093.969530152321, 5831.533477321889, 5937.495312898279, 6033.0, 6039.728212230372, 6053.303870055, 6030.48740857032, 6117.827404800828, 6128.601640893506, 6130.417610327012, 6132.327596972871, 5962.25471816023, 5969.492593129459, 5970.940290597212, 5974.312954010345, 6008.24896887889], "value": 5674.362385597693, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.942625450582, 616.8365383173458, 646.476354153136, 660.6003367962381, 663.2306524431655, 36338.27323453529, 47772.52227477724, 48860.755696221466, 48970.51029489704, 51333.0, 47438.0, 47790.522094779044, 47885.521144788545, 47896.28155577669, 47943.28085078727, 8533.487990720545, 9331.066893310683, 9484.762880927963, 9494.715158545254, 11117.72205694856, 10907.509162087725, 11419.371934543597, 11420.371879546621, 11427.428628568538, 11429.885701143006, 11466.827997580043, 11512.194146409754, 11527.48126334317, 11530.65408037756, 11532.25040372373, 11360.375179365132, 11388.658340249764, 11434.313941163473, 11459.02598279143, 11465.598704045318, 11243.66269011931, 11294.265872718248, 11308.151888608387, 11323.490442930088, 11325.32048077118, 46975.060498790175, 47262.76368618169, 47321.76339118282, 47383.76308118437, 47408.525914740756, 3944.7435916665327, 3966.6033396660105, 4579.358889755437, 4705.670603057787, 4754.120487709784, 34856.30287394238, 35338.82330588331, 35399.64600353989, 35598.82200589006, 37089.62910370889, 4687.250039993584, 4701.0, 4757.429108507002, 4760.166970780099, 4834.9516504834855, 377799.99999999726, 379100.0, 379613.0, 380302.0, 382447.0, 67926.66036669884, 68290.31709682939, 68294.0, 68438.31561684319, 68467.65766171138, 40301.39547906784, 40883.0, 41086.38370424446, 71555.0, 71763.6411817948, 19916.60166796656, 20017.59964800725, 20030.098645560985, 20036.799632003786, 20170.798292017185, 13717.17696938187, 13967.0, 14043.368048437846, 14063.929680351534, 15389.307481163374, 21606.351809445743, 21690.783092169193, 21862.45343866416, 21970.89014554949, 21978.120875164816, 7434.962825185839, 7470.4023678106705, 7623.275788800055, 7624.8475030500185, 7905.209479052164, 1041.4844651897358, 1090.9781804363868, 1120.355795417638, 1135.432283858071, 1158.9246698964541, 34652.65347346545, 34815.30369392598, 35117.82441087778, 35196.47205291923, 35355.0, 1140.680609429361, 1153.5847095045776, 1200.4117982188666, 1236.3509157692283, 1297.5199176304827, 18711.0, 18745.156467958976, 18910.338138165098, 18940.337088202123, 18995.335163269217, 628557.0, 634294.0, 636063.0, 636939.0, 640313.0, 65454.34545654578, 66004.33995660079, 66124.33875661182, 66125.6693716538, 66149.33850661528, 1357.8166947461966, 1434.211183848879, 1479.2751551739784, 1483.3547406878258, 1500.7918625506572, 33444.0, 35499.64500354963, 35751.28497430089, 35906.4614030795, 35917.28165436624, 56222.71888640694, 56762.0, 56769.0, 56850.0, 56955.0, 459053.0, 462021.0, 462657.0, 462881.0, 463928.0, 30526.0, 31207.687923121386, 31747.84126079355, 33019.504707428925, 33221.50167747534, 52304.73847630737, 52413.475865242384, 53112.0, 53116.73441632767, 53412.73293633662, 43444.532961562196, 44901.0, 45295.547044529136, 45609.771951140036, 47085.76457117692, 44712.0, 45853.31220031769, 45963.54036459592, 46442.08537403919, 46534.30198547092, 44634.0, 45359.0, 45985.08029839318, 46160.0, 46528.0, 38395.0, 39909.80045099756, 40062.79968600138, 40555.59444405518, 40908.0334027987, 28518.714812852435, 29014.27464313409, 29045.85477072601, 29178.0, 29254.561181582718], "value": 22515.45663854869, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.4540225293602, 413.2561389498901, 429.2059689574287, 431.44990137574587, 437.5864807756669, 15269.0, 19987.100580473878, 20155.798442015595, 20708.585828283445, 20863.37409877702, 20236.797632023674, 20287.18851245952, 20305.0, 20354.08406621702, 20537.691934620994, 5795.4784069433745, 5855.063189889618, 5864.325602555718, 5899.6165249258875, 5906.822795316126, 5786.0, 5839.036558967765, 5851.093080572497, 5852.297724273072, 5853.560982926277, 5733.541316694655, 5735.053716136853, 5755.856103597402, 5791.247137872071, 5878.2064421303, 5562.471565201299, 5843.766249350021, 5857.648541087548, 5865.011791981451, 5865.442782935615, 5146.073706732786, 5278.049951008817, 5299.125644268691, 5300.734963251822, 5303.151495760699, 24158.0, 24298.878505607536, 24393.63409548858, 24516.387090322885, 24704.258872233862, 2653.509100816355, 2666.3600735823456, 2747.4779791839687, 3070.47801873682, 3343.264481813997, 15254.38982440701, 15450.690986180325, 15498.457553985669, 15500.922495387564, 15513.0, 2060.783617720133, 2108.1988844239245, 2119.9576008479744, 2135.626265403548, 2192.3751730756817, 54256.0, 58204.708976454844, 65899.34100659029, 67518.66240668815, 80555.99999999942, 11234.55061797519, 11334.263272887236, 11452.141089418326, 11592.88407115918, 11709.824352634718, 16079.43721969749, 16114.919425402797, 16187.838121618868, 16190.59523511898, 16203.918980405258, 9095.863562046574, 9122.86315705265, 9136.314776391797, 9191.63233470667, 9358.719238422858, 8802.735917922473, 9053.139951704577, 9066.637334506679, 9211.355205135576, 9256.352055356061, 7047.506674532733, 7134.500584959107, 7146.642667866586, 7169.24722904098, 7233.421326293884, 1980.227711192636, 1985.5731017831235, 1989.3634037107986, 2096.7379077615296, 2112.5669237806214, 932.8265142218614, 996.0985308296003, 1009.8737657792776, 1025.1491262252312, 1113.3765091548757, 20738.688919666216, 21525.13899444036, 21635.459113522287, 21730.348089557338, 21819.127234910426, 1037.4190453346, 1066.7279843639822, 1068.257560995101, 1071.8124328242527, 1082.118073769875, 11269.774604507864, 12243.877561224452, 12267.509299627915, 12458.501659933501, 12499.187552809039, 549418.0, 549899.0, 550033.0, 562028.0, 573303.0, 65142.34857651363, 65955.67022164955, 69451.0, 90271.0, 103666.48166759119, 1041.2138835179542, 1089.3844977587637, 1094.272308914563, 1097.5006372100675, 1102.2339474065448, 18738.344157954405, 18803.717944230575, 18882.52793680114, 19213.711794323375, 19339.806601934364, 45411.545884540734, 45435.77282113568, 45878.31182532198, 46125.53874461347, 46420.30369544527, 63658.68170659117, 64453.35546644474, 64774.35225647872, 66031.33968660251, 67337.0, 8809.911900881165, 8866.86699699492, 8927.508987005518, 8950.34506814146, 9130.543472826199, 9310.441373517611, 10109.39343639384, 13285.468581256642, 14106.858931410554, 15012.6997460048, 12243.510259589517, 12325.691857703629, 12494.750104997667, 12693.238405695687, 12798.232106073665, 12062.095342849147, 12144.453499592717, 12260.203086799329, 12584.119111662096, 12741.426635801596, 12188.817167742669, 12604.432800523815, 12879.098463107492, 12943.741125177632, 13055.738885222432, 8789.824203516022, 8874.0, 8942.50816205115, 8959.73120806377, 9102.681406150752, 9430.858537121812, 12018.158728888893, 12312.630621081382, 12629.74740505203, 13196.538121166097], "value": 9863.296097029914, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1550.6200980759716, 1598.3926108078929, 1785.401890366727, 1821.107657247949, 1879.0698604190918, 9318.114779095991, 9959.302848800588, 9974.501274936252, 9974.551145198457, 9988.600455981752, 8419.32645388369, 8648.091950345202, 8651.610677519511, 8855.158759917798, 9778.951105244463, 2327.4879526504183, 2449.951000979979, 2458.815588830836, 2458.9016439342404, 2469.4196863737006, 2434.1480481831304, 2436.196055301746, 2467.4571594249237, 2491.6885389326335, 2496.987515062422, 2454.7422520635346, 2470.5429495543294, 2490.950180996379, 2495.189063554343, 2515.861627610481, 2481.3176376496426, 2485.9129930452345, 2498.750124987505, 2507.3104896153523, 2513.635522849195, 2364.2552595932225, 2382.1305223593436, 2383.2016274548046, 2387.0929046962215, 2387.7254115776736, 9467.66863159793, 9521.857172142423, 9531.618735250651, 9545.52272386378, 9546.093121153479, 5538.833834984957, 5718.256626638552, 5727.684977326246, 5729.4843464088435, 5730.283714535683, 3527.4708793680898, 3584.5409180141028, 3722.590515043343, 3724.491580909709, 3730.123420996064, 1188.4295538141655, 1220.3837062283578, 1228.9508419663202, 1231.4889320931802, 1241.1994263699953, 13465.461381544726, 13589.048766586342, 13668.863311366858, 13677.589672309747, 13684.863151368458, 4912.656114072052, 5007.749612519359, 5155.32980712505, 5174.844754657366, 5190.221466779938, 11647.6505704829, 11651.941740291244, 11679.416029198506, 11693.766124677459, 11698.06415486759, 5732.656040637492, 5812.244408226903, 5824.0, 5824.126381042793, 5849.151872978479, 5146.099432599355, 5223.869403264873, 5224.869378265497, 5229.398619158769, 5343.305370301836, 4217.219814334358, 4236.025714085716, 4252.107057517956, 4347.5652434756275, 4361.0, 1317.2952470428274, 1360.3130419138272, 1376.2499437806418, 1385.7020740540831, 1388.187910072619, 1023.8208313545249, 1060.3637817309552, 1071.7481391872905, 1074.4573990134932, 1087.4508373271447, 6256.812295631138, 6271.7804876830005, 6618.205815302098, 6624.834379140464, 6697.229818570927, 495.17306098815067, 502.87176769923434, 1107.4573459005192, 1125.178619607691, 1141.9200655954169, 4286.37847512109, 4616.746078965622, 4683.1336202802595, 4767.974885399656, 4788.7366194859615, 11673.941630291964, 12208.572699955459, 12289.56986505468, 12346.444410001392, 12376.690582735504, 12061.276323420447, 12245.571405000783, 12288.016958643288, 12312.569060082855, 12322.81515777264, 8695.521746304014, 8804.515751633593, 8817.867731984026, 8825.867611985825, 8828.07305232954, 6247.250329960252, 6363.6818159090935, 6460.25707043696, 6473.093766872736, 6510.121133646897, 10694.73263168427, 10750.13998880072, 10784.946075269887, 10786.568537258421, 10807.513661885077, 10744.51649675751, 10914.617988370368, 11043.282186657842, 11304.49129789145, 11313.604023859125, 8305.211004954519, 8429.747107586782, 8503.617337219715, 8519.190676885686, 8526.531040792568, 9412.294077944045, 9432.103950124727, 9510.619575216913, 9532.475713835805, 9536.141747242782, 8269.131741167092, 8328.875066873881, 8547.572621368783, 8610.65557377698, 8796.340274479311, 7671.232876712284, 7759.534427934342, 7780.727674531364, 7786.7663970080985, 7867.84264314722, 7870.212978702084, 7884.132745398117, 7950.045994480699, 7952.284294413531, 7989.121196668478, 5893.823185304448, 5950.583459157818, 6009.579329446897, 6065.272167339947, 6093.9085913712215, 5662.377138514842, 5845.970770146122, 5863.501602363847, 5929.822105336847, 6129.049997250433], "value": 5040.2801707603585, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.18872028174354, 352.0758010223164, 357.7781775299314, 361.2756423371144, 365.4810169559227, 5511.5039646431815, 5535.640183388077, 5540.806071787489, 5558.638688485251, 5612.943870561293, 5498.890022199554, 5518.075420027392, 5521.309836270466, 5525.784327447965, 5538.9169162462595, 3873.4383514390397, 4119.642491369532, 4246.02341461464, 4334.956650433503, 4359.455068116486, 4341.9782901085455, 4343.5004974427875, 4347.456567929009, 4373.606375426211, 4393.692441529094, 4175.68682348825, 4330.307150855863, 4339.153864996327, 4380.386745855582, 4381.583749543788, 4095.344744840841, 4300.440942677463, 4319.589638984291, 4356.520782713899, 4372.169287835333, 3872.3610604250266, 3875.147467557133, 3880.3403421418425, 3887.436321733333, 3909.902252443683, 5246.140853234996, 5367.463253674601, 5398.109311963561, 5404.729763511848, 5432.728363581805, 4917.0903382874285, 4927.408710954673, 4927.90144197114, 4961.0, 4962.429320628102, 5681.892031053946, 5683.857903552403, 5696.487316141525, 5706.3152421709665, 5814.767409303623, 4115.382692596105, 4264.658827293809, 4276.358546218061, 4289.871303860889, 4295.72077814941, 5596.916046259309, 5835.737391817378, 5936.693927336022, 5948.672822994734, 5949.762009519614, 4345.717528360647, 4353.10761293934, 4358.782060897006, 4376.562343765598, 4387.517373088957, 4426.291793313118, 4426.557344265548, 4432.800523976429, 4435.800388982503, 4441.889527618096, 4858.489858564871, 4972.353594032753, 4982.676126051795, 4989.850304490871, 5001.7499125043605, 5674.744636491368, 5735.512481439125, 5755.366909639936, 5756.683382414008, 5757.825576457035, 1501.1893577468045, 1513.3719506404939, 1513.5610672904734, 1525.7101150781316, 1539.2303848075962, 938.5354249646468, 975.7316737896994, 985.9795112059128, 990.5493000684671, 1003.6186249225248, 4918.5819205367225, 4930.852074437773, 4961.875953101201, 4964.8758781030765, 5003.473940448125, 5182.170852663556, 5191.987562425366, 5205.687658740488, 5217.3739251220795, 5226.790928362823, 4966.90066198674, 4983.202687570044, 4985.177445721488, 5259.447757985433, 5703.201551782754, 3141.858616362269, 3191.0586377018835, 3198.856051477689, 3201.4717571600427, 3208.502682084281, 6037.969810151009, 6147.723352449061, 6207.472364848948, 6222.253329600387, 6239.719212635442, 6020.0, 6058.7882423515475, 6092.360302168208, 6095.7866474674065, 6115.969420152871, 6115.938840611626, 6171.932402645164, 6184.567080304336, 6194.5973511721595, 6202.7208775606105, 5847.5906686533235, 5932.970335148297, 5982.192404025401, 5985.25184351956, 6015.789447369321, 5928.3775203603, 5937.851553711192, 5980.252468441445, 5987.2815262168815, 5991.2510936132985, 5889.764409423575, 5943.435373639497, 6105.542084343603, 6137.526993521612, 6137.907931380943, 4223.134257477272, 4237.427947227085, 4266.466691663542, 4276.358546217968, 4282.764447955392, 5801.970990145022, 6017.488513476404, 6032.245969253843, 6045.969770151297, 6055.515558755201, 5810.738516766671, 5839.854003649943, 5849.795257165979, 5868.119782032729, 5879.235699359056, 5846.532277417711, 5849.853753656193, 5851.074462768615, 5852.502537284208, 5870.119482077552, 5598.285711444621, 5686.09022556389, 5782.306123265235, 5788.797392091256, 5828.076734677462, 5902.498287645598, 5903.173555702119, 5932.347441781314, 5934.821955341346, 5941.821745347646, 5466.01611709896, 5680.40948534411, 5735.627184233012, 5753.252077229933, 5772.393898640582], "value": 4410.263375272954, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.7880714592771, 739.7299985505292, 792.3265224559126, 875.5578432891389, 924.4197579845269, 16582.170891455426, 16871.24079416426, 17775.73336399955, 17850.928944263338, 18293.817061829377, 17132.571685707862, 17840.732389014174, 17968.0, 17983.460496185104, 17987.280508779666, 863.7667829685981, 891.3270480786997, 896.9820603587924, 897.3270047464401, 898.1198425542972, 742.2503271695599, 860.849351363512, 877.0527829943677, 881.5460038080394, 881.7839629290801, 926.5043201886958, 929.8977112517617, 930.4045410937006, 930.5161316115616, 930.9255259579218, 923.5936188077225, 923.6674797073082, 923.8290916180495, 924.2421214604027, 925.3383830561147, 560.9102543593006, 652.5921299188008, 665.5574043261225, 669.9464042876558, 674.6795272245693, 16012.359505619761, 16047.197640117947, 16060.11669358185, 16068.116253606047, 16069.357225710957, 15482.922585387001, 15725.92137039319, 15816.52550423489, 16050.5987350316, 16284.674306513803, 13339.666508337272, 13428.19430834153, 13608.0, 14345.856541434661, 14831.258437078102, 4200.495940487131, 4236.5128010278595, 4300.311950088002, 4323.286657701507, 4350.608445239912, 65136.67431662859, 65490.0, 69189.65405172942, 78193.0, 79136.20863791346, 12340.197887137307, 12420.192687475284, 12591.307478088793, 12603.558875439316, 12761.553345633043, 21648.567028659338, 21879.12483500642, 21881.234156804436, 21911.342659720234, 22045.669314960287, 8749.693760718344, 8760.4305720128, 9043.68347107848, 9084.591193396313, 9500.809983800285, 5096.286519887219, 5344.251804747339, 5395.81114660985, 5475.397706252309, 5482.17767334903, 3912.158885839558, 4071.1654110907784, 4075.083106301118, 4080.3063479208604, 4081.469408976873, 664.9368310010541, 674.8279188806921, 679.2256827216977, 689.2349492063805, 692.0933577014127, 796.8485987662442, 828.411827602397, 842.1957031035347, 845.8815765792793, 882.2148288023664, 10516.0, 10697.0, 10862.402567858844, 11125.888741112485, 11540.884591153981, 764.1746913333586, 779.8284377436955, 805.2068712318362, 814.1817473439274, 839.7029810942887, 5091.338126043591, 5202.3497062867145, 5204.921926171035, 5279.815206467755, 5294.258803767476, 82982.5850870754, 120862.0, 130865.34567327102, 139868.0, 143481.0, 111932.0, 114009.0, 114272.0, 116544.41727791307, 116898.0, 76415.61792191115, 78809.0, 80067.0, 101595.49202254089, 104853.0, 10372.0, 10567.193567795195, 10689.89310106889, 10832.67501974942, 11210.66368008961, 57955.42044579669, 58122.418775811704, 58637.70681146567, 59251.40748592631, 61197.0, 47270.0, 51354.74322628363, 51533.48466515287, 51690.0, 73630.63184684256, 13902.721945561234, 14242.501512447428, 14567.854321456649, 14872.851271487147, 15064.171470569225, 15798.0, 16569.66860662804, 16792.916035419745, 17324.047177405362, 18083.909580452015, 15375.154366509501, 15570.14364209934, 15756.054636721834, 15801.209939502749, 15907.363705451653, 14822.555323340317, 15399.3070311834, 15563.688726225639, 16137.75793363124, 16145.1927403627, 13495.055346125677, 13951.093693765763, 14149.504767333094, 14344.354504047551, 14766.48317308932, 11633.883661163618, 12128.332941688292, 12218.144729868824, 12596.055295852664, 12879.613611591667, 11821.527138914691, 11945.82081268799, 12186.51253949832, 12350.505979760708, 12680.429380678077], "value": 8230.502546587517, "unit": "score"}], "name": "dromaeo_css", "value": 7830.719313145256, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 110.0, 110.0, 113.0, 113.0, 112.0, 111.0, 110.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 118.0, 176.0, 116.0, 119.0, 149.0, 116.0, 156.0, 116.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 159.0, 160.0, 160.0, 162.0, 161.0, 162.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 107.0, 76.0, 76.0, 75.0, 79.0, 98.0, 74.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 134.0, 83.0, 82.0, 82.0, 110.0, 83.0, 84.0, 125.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 51.0, 50.0, 49.0, 50.0, 51.0, 49.0, 49.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 60.0, 59.0, 60.0, 61.0, 61.0, 60.0, 61.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 72.0, 78.0, 72.0, 72.0, 72.0, 81.0, 78.0, 72.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 128.0, 116.0, 117.0, 125.0, 125.0, 116.0, 127.0, 157.0, 120.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 152.0, 155.0, 155.0, 158.0, 154.0, 158.0, 152.0, 159.0, 154.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 60.0, 63.0, 62.0, 61.0, 60.0, 61.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1463.8000000000002, "alertThreshold": 2.0}]} 13:44:39 INFO - Return code: 0 13:44:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:44:39 INFO - # TBPL SUCCESS # 13:44:39 INFO - Running post-action listener: _resource_record_post_action 13:44:39 INFO - [mozharness: 2016-08-25 20:44:39.741335Z] Finished run-tests step (success) 13:44:39 INFO - Running post-run listener: _resource_record_post_run 13:44:39 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 270336; Write bytes: 330178560; Read time: 44; Write time: 242304 13:44:39 INFO - TinderboxPrint: CPU usage
12.9% 13:44:39 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 44 13:44:39 INFO - TinderboxPrint: I/O write bytes / time
330,178,560 / 242,304 13:44:39 INFO - TinderboxPrint: CPU idle
8,253.6 (87.0%) 13:44:39 INFO - TinderboxPrint: CPU user
1,185.7 (12.5%) 13:44:39 INFO - TinderboxPrint: Swap in / out
0 / 0 13:44:39 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78790656; Read time: 0; Write time: 85804 13:44:39 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 266240; Write bytes: 251015168; Read time: 32; Write time: 156480 13:44:40 INFO - Running post-run listener: _upload_blobber_files 13:44:40 INFO - Blob upload gear active. 13:44:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:44:40 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 13:44:40 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'] 13:44:40 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 13:44:40 INFO - (blobuploader) - INFO - Open directory for files ... 13:44:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:44:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:44:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:44:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:44:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:44:44 INFO - (blobuploader) - INFO - Done attempting. 13:44:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:44:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:44:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:44:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:44:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:44:45 INFO - (blobuploader) - INFO - Done attempting. 13:44:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:44:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:44:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:44:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:44:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:44:46 INFO - (blobuploader) - INFO - Done attempting. 13:44:46 INFO - (blobuploader) - INFO - Iteration through files over. 13:44:46 INFO - Return code: 0 13:44:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:44:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:44:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ee35f309aeadd6a36bb62ed792da5c3b9e7fb7b4e8686b5bb983b295959ccb8aa6ab8e4e8562ef9313da7565025b0a2576d9c23888d1847d3f62281e7fc8028", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7487d0c7c862f5e1132be0c82646e1c7dc2bde495188195ab0e542f59121dee92944e4b2409f341ee21cac16a3a837e7b685b40458d6fbcb2b24ca7c061e1cd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4f57f624f4c018c61ebf779125f5cf4cdab2ef7fd2e569bbd4424ef6b8ed27fef405ff56fd3397ece9878a516f73f307d94c023a962cac6b5189dfcb25626df"} 13:44:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:44:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:44:46 INFO - Contents: 13:44:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ee35f309aeadd6a36bb62ed792da5c3b9e7fb7b4e8686b5bb983b295959ccb8aa6ab8e4e8562ef9313da7565025b0a2576d9c23888d1847d3f62281e7fc8028", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7487d0c7c862f5e1132be0c82646e1c7dc2bde495188195ab0e542f59121dee92944e4b2409f341ee21cac16a3a837e7b685b40458d6fbcb2b24ca7c061e1cd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4f57f624f4c018c61ebf779125f5cf4cdab2ef7fd2e569bbd4424ef6b8ed27fef405ff56fd3397ece9878a516f73f307d94c023a962cac6b5189dfcb25626df"} 13:44:46 INFO - Running post-run listener: copy_logs_to_upload_dir 13:44:46 INFO - Copying logs to upload dir... 13:44:46 INFO - mkdir: /builds/slave/test/build/upload/logs 13:44:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.625382 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-25 13:44:45.506319) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 13:44:45.513424) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425 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/4ee35f309aeadd6a36bb62ed792da5c3b9e7fb7b4e8686b5bb983b295959ccb8aa6ab8e4e8562ef9313da7565025b0a2576d9c23888d1847d3f62281e7fc8028", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7487d0c7c862f5e1132be0c82646e1c7dc2bde495188195ab0e542f59121dee92944e4b2409f341ee21cac16a3a837e7b685b40458d6fbcb2b24ca7c061e1cd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4f57f624f4c018c61ebf779125f5cf4cdab2ef7fd2e569bbd4424ef6b8ed27fef405ff56fd3397ece9878a516f73f307d94c023a962cac6b5189dfcb25626df"} build_url:https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/LG8y-wzRQL2shaU_7OafnQ/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/4ee35f309aeadd6a36bb62ed792da5c3b9e7fb7b4e8686b5bb983b295959ccb8aa6ab8e4e8562ef9313da7565025b0a2576d9c23888d1847d3f62281e7fc8028", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7487d0c7c862f5e1132be0c82646e1c7dc2bde495188195ab0e542f59121dee92944e4b2409f341ee21cac16a3a837e7b685b40458d6fbcb2b24ca7c061e1cd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4f57f624f4c018c61ebf779125f5cf4cdab2ef7fd2e569bbd4424ef6b8ed27fef405ff56fd3397ece9878a516f73f307d94c023a962cac6b5189dfcb25626df"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 13:44:45.542165) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:44:45.542511) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1472156207.275174-1395837425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 13:44:45.625717) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 13:44:45.626422) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 13:44:45.627188) ========= ========= Total master_lag: 0.19 =========