builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0042 starttime: 1462889864.88 results: success (0) buildid: 20160510043819 builduid: ba4ea0efd99643e28823962e68c5850f revision: 1ded8982a100a4b703be202f8d4d303fcf34b316 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:44.876854) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:44.877308) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:44.877587) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:44.895396) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:44.895783) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yFxmtyRx4v/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-10 07:17:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 38.2M=0s 2016-05-10 07:17:45 (38.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089628 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:45.002109) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:45.002486) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yFxmtyRx4v/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.019883 ========= master_lag: 0.16 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:45.181781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 07:17:45.182159) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1ded8982a100a4b703be202f8d4d303fcf34b316 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1ded8982a100a4b703be202f8d4d303fcf34b316 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yFxmtyRx4v/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-10 07:17:45,308 truncating revision to first 12 chars 2016-05-10 07:17:45,308 Setting DEBUG logging. 2016-05-10 07:17:45,308 attempt 1/10 2016-05-10 07:17:45,308 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1ded8982a100?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 07:17:46,377 unpacking tar archive at: fx-team-1ded8982a100/testing/mozharness/ program finished with exit code 0 elapsedTime=1.318031 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 07:17:46.516743) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:46.517043) ========= script_repo_revision: 1ded8982a100a4b703be202f8d4d303fcf34b316 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:46.517411) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:46.517675) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 07:17:46.530840) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-10 07:17:46.531142) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yFxmtyRx4v/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:17:46 INFO - MultiFileLogger online at 20160510 07:17:46 in /builds/slave/test 07:17:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:17:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:17:46 INFO - {'append_to_log': False, 07:17:46 INFO - 'base_work_dir': '/builds/slave/test', 07:17:46 INFO - 'blob_upload_branch': 'Fx-Team', 07:17:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:17:46 INFO - 'branch': 'Fx-Team', 07:17:46 INFO - 'buildbot_json_path': 'buildprops.json', 07:17:46 INFO - 'config_files': ('talos/mac_config.py',), 07:17:46 INFO - 'default_actions': ('clobber', 07:17:46 INFO - 'read-buildbot-config', 07:17:46 INFO - 'download-and-extract', 07:17:46 INFO - 'populate-webroot', 07:17:46 INFO - 'create-virtualenv', 07:17:46 INFO - 'install', 07:17:46 INFO - 'run-tests'), 07:17:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:17:46 INFO - 'download_minidump_stackwalk': True, 07:17:46 INFO - 'download_symbols': 'ondemand', 07:17:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:17:46 INFO - 'tooltool.py': '/tools/tooltool.py', 07:17:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:17:46 INFO - '/tools/misc-python/virtualenv.py')}, 07:17:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:17:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:17:46 INFO - 'installer_path': 'installer.exe', 07:17:46 INFO - 'log_level': 'info', 07:17:46 INFO - 'log_name': 'talos', 07:17:46 INFO - 'log_to_console': True, 07:17:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:17:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:17:46 INFO - 'opt_config_files': (), 07:17:46 INFO - 'pip_index': False, 07:17:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:17:46 INFO - 'cmd': ('bash', 07:17:46 INFO - '-c', 07:17:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:17:46 INFO - 'enabled': True, 07:17:46 INFO - 'halt_on_failure': False, 07:17:46 INFO - 'name': 'check_screen_resolution'},), 07:17:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:17:46 INFO - 'cmd': ('bash', 07:17:46 INFO - '-c', 07:17:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:17:46 INFO - 'enabled': True, 07:17:46 INFO - 'halt_on_failure': False, 07:17:46 INFO - 'name': 'check_screen_resolution'},), 07:17:46 INFO - 'run_cmd_checks_enabled': True, 07:17:46 INFO - 'sps_profile': False, 07:17:46 INFO - 'sps_profile_interval': 0, 07:17:46 INFO - 'suite': 'dromaeojs', 07:17:46 INFO - 'system_bits': '32', 07:17:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:17:46 INFO - 'title': 't-yosemite-r7-0042', 07:17:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:17:46 INFO - 'use_talos_json': True, 07:17:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:17:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:17:46 INFO - 'work_dir': 'build'} 07:17:46 INFO - ##### 07:17:46 INFO - ##### Running clobber step. 07:17:46 INFO - ##### 07:17:46 INFO - Running pre-action listener: _resource_record_pre_action 07:17:46 INFO - Running main action method: clobber 07:17:46 INFO - rmtree: /builds/slave/test/build 07:17:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:17:47 INFO - Running post-action listener: _resource_record_post_action 07:17:47 INFO - ##### 07:17:47 INFO - ##### Finished clobber step (success) 07:17:47 INFO - ##### 07:17:47 INFO - ##### 07:17:47 INFO - ##### Running read-buildbot-config step. 07:17:47 INFO - ##### 07:17:47 INFO - Running pre-action listener: _resource_record_pre_action 07:17:47 INFO - Running main action method: read_buildbot_config 07:17:47 INFO - Using buildbot properties: 07:17:47 INFO - { 07:17:47 INFO - "project": "", 07:17:47 INFO - "product": "firefox", 07:17:47 INFO - "script_repo_revision": "production", 07:17:47 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:17:47 INFO - "repository": "", 07:17:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:17:47 INFO - "buildid": "20160510043819", 07:17:47 INFO - "pgo_build": "False", 07:17:47 INFO - "basedir": "/builds/slave/test", 07:17:47 INFO - "buildnumber": 5, 07:17:47 INFO - "slavename": "t-yosemite-r7-0042", 07:17:47 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:17:47 INFO - "platform": "yosemite_r7", 07:17:47 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:17:47 INFO - "branch": "fx-team", 07:17:47 INFO - "repo_path": "integration/fx-team", 07:17:47 INFO - "revision": "1ded8982a100a4b703be202f8d4d303fcf34b316", 07:17:47 INFO - "stage_platform": "macosx64", 07:17:47 INFO - "builduid": "ba4ea0efd99643e28823962e68c5850f", 07:17:47 INFO - "slavebuilddir": "test" 07:17:47 INFO - } 07:17:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:17:47 INFO - Running post-action listener: _resource_record_post_action 07:17:47 INFO - ##### 07:17:47 INFO - ##### Finished read-buildbot-config step (success) 07:17:47 INFO - ##### 07:17:47 INFO - ##### 07:17:47 INFO - ##### Running download-and-extract step. 07:17:47 INFO - ##### 07:17:47 INFO - Running pre-action listener: _resource_record_pre_action 07:17:47 INFO - Running main action method: download_and_extract 07:17:47 INFO - mkdir: /builds/slave/test/build/tests 07:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:47 INFO - https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:17:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:17:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:17:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:17:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 07:17:49 INFO - Downloaded 1199 bytes. 07:17:49 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:17:49 INFO - Using the following test package requirements: 07:17:49 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:17:49 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:17:49 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:17:49 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:17:49 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:17:49 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:17:49 INFO - u'jsshell-mac.zip'], 07:17:49 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:17:49 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:17:49 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:17:49 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:17:49 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:17:49 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:17:49 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:17:49 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:17:49 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:17:49 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:17:49 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:17:49 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:17: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')]} 07:17:49 INFO - https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:17:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:17:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:17:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:17:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:17:50 INFO - Downloaded 18149650 bytes. 07:17:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:17:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:17:53 INFO - Return code: 0 07:17:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:53 INFO - https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:17:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:17:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:17:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:17:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:17:54 INFO - Downloaded 18149650 bytes. 07:17:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:17:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:17:58 INFO - Return code: 0 07:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:58 INFO - https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:17:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:17:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:17:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:17:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:17:59 INFO - Downloaded 10840152 bytes. 07:17:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:17:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:17:59 INFO - Return code: 0 07:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:59 INFO - https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:17:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:17:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:17:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 07:18:02 INFO - Downloaded 109781253 bytes. 07:18:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:18:02 INFO - mkdir: /builds/slave/test/properties 07:18:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:02 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:02 INFO - Contents: 07:18:02 INFO - build_url:https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:18:03 INFO - Running post-action listener: _resource_record_post_action 07:18:03 INFO - Running post-action listener: set_extra_try_arguments 07:18:03 INFO - ##### 07:18:03 INFO - ##### Finished download-and-extract step (success) 07:18:03 INFO - ##### 07:18:03 INFO - ##### 07:18:03 INFO - ##### Running populate-webroot step. 07:18:03 INFO - ##### 07:18:03 INFO - Running pre-action listener: _resource_record_pre_action 07:18:03 INFO - Running main action method: populate_webroot 07:18:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:03 INFO - '--tppagecycles', 07:18:03 INFO - '7'], 07:18:03 INFO - 'tests': ['tsvgm']}}, 07:18:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:03 INFO - 'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:03 INFO - 'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['damp', 'tps']}, 07:18:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:03 INFO - 'tests': ['damp', 'tps']}, 07:18:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['dromaeo_dom']}, 07:18:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['a11yr', 07:18:03 INFO - 'ts_paint', 07:18:03 INFO - 'tpaint', 07:18:03 INFO - 'sessionrestore', 07:18:03 INFO - 'sessionrestore_no_auto_restore', 07:18:03 INFO - 'tabpaint']}, 07:18:03 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:03 INFO - 'ts_paint', 07:18:03 INFO - 'tpaint', 07:18:03 INFO - 'sessionrestore', 07:18:03 INFO - 'sessionrestore_no_auto_restore', 07:18:03 INFO - 'tabpaint']}, 07:18:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:03 INFO - 'ts_paint', 07:18:03 INFO - 'tpaint', 07:18:03 INFO - 'sessionrestore', 07:18:03 INFO - 'sessionrestore_no_auto_restore', 07:18:03 INFO - 'tabpaint']}, 07:18:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:03 INFO - 'ts_paint', 07:18:03 INFO - 'tpaint', 07:18:03 INFO - 'sessionrestore', 07:18:03 INFO - 'sessionrestore_no_auto_restore', 07:18:03 INFO - 'tabpaint']}, 07:18:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['a11yr', 07:18:03 INFO - 'ts_paint', 07:18:03 INFO - 'tpaint', 07:18:03 INFO - 'sessionrestore', 07:18:03 INFO - 'sessionrestore_no_auto_restore', 07:18:03 INFO - 'tabpaint']}, 07:18:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['a11yr', 07:18:03 INFO - 'ts_paint', 07:18:03 INFO - 'tpaint', 07:18:03 INFO - 'sessionrestore', 07:18:03 INFO - 'sessionrestore_no_auto_restore', 07:18:03 INFO - 'tabpaint']}, 07:18:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['tsvgx', 07:18:03 INFO - 'tsvgr_opacity', 07:18:03 INFO - 'tart', 07:18:03 INFO - 'tscrollx', 07:18:03 INFO - 'cart']}, 07:18:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:03 INFO - 'tsvgr_opacity', 07:18:03 INFO - 'tart', 07:18:03 INFO - 'tscrollx', 07:18:03 INFO - 'cart']}, 07:18:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:03 INFO - 'talos_options': ['--disable-e10s'], 07:18:03 INFO - 'tests': ['tp5o']}, 07:18:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:03 INFO - 'tests': ['tp5o']}, 07:18:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:03 INFO - 'talos_options': ['--disable-e10s', 07:18:03 INFO - '--xperf_path', 07:18:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:03 INFO - 'tests': ['tp5n']}, 07:18:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:03 INFO - 'talos_options': ['--xperf_path', 07:18:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:03 INFO - 'tests': ['tp5n']}}, 07:18:03 INFO - 'talos.zip': {'path': '', 07:18:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:03 INFO - Running post-action listener: _resource_record_post_action 07:18:03 INFO - ##### 07:18:03 INFO - ##### Finished populate-webroot step (success) 07:18:03 INFO - ##### 07:18:03 INFO - ##### 07:18:03 INFO - ##### Running create-virtualenv step. 07:18:03 INFO - ##### 07:18:03 INFO - Running pre-action listener: _resource_record_pre_action 07:18:03 INFO - Running main action method: create_virtualenv 07:18:03 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:18:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:03 INFO - Using real prefix '/tools/python27' 07:18:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:06 INFO - Installing pip.................done. 07:18:06 INFO - Return code: 0 07:18:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:06 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 0x10faa8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc083cd46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:06 INFO - 'HOME': '/Users/cltbld', 07:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:06 INFO - 'LOGNAME': 'cltbld', 07:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:06 INFO - 'MOZ_NO_REMOTE': '1', 07:18:06 INFO - 'NO_EM_RESTART': '1', 07:18:06 INFO - 'PAGER': '/bin/cat', 07:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:06 INFO - 'PWD': '/builds/slave/test', 07:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:06 INFO - 'SHELL': '/bin/bash', 07:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:18:06 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:06 INFO - 'USER': 'cltbld', 07:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:06 INFO - 'XPC_FLAGS': '0x0', 07:18:06 INFO - 'XPC_SERVICE_NAME': '0', 07:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:18:07 INFO - Cleaning up... 07:18:07 INFO - Return code: 0 07:18:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:07 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faa8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc083cd46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:07 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>=0.7.1'] in /builds/slave/test/build 07:18:07 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>=0.7.1 07:18:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:18:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:07 INFO - 'HOME': '/Users/cltbld', 07:18:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:07 INFO - 'LOGNAME': 'cltbld', 07:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:07 INFO - 'MOZ_NO_REMOTE': '1', 07:18:07 INFO - 'NO_EM_RESTART': '1', 07:18:07 INFO - 'PAGER': '/bin/cat', 07:18:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:07 INFO - 'PWD': '/builds/slave/test', 07:18:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:07 INFO - 'SHELL': '/bin/bash', 07:18:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:18:07 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:18:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:07 INFO - 'USER': 'cltbld', 07:18:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:07 INFO - 'XPC_FLAGS': '0x0', 07:18:07 INFO - 'XPC_SERVICE_NAME': '0', 07:18:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:07 INFO - Downloading/unpacking psutil>=0.7.1 07:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:10 INFO - Installing collected packages: psutil 07:18:10 INFO - Running setup.py install for psutil 07:18:10 INFO - building 'psutil._psutil_osx' extension 07:18:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:18:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:18:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:18:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:18:10 INFO - building 'psutil._psutil_posix' extension 07:18:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:18:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:11 INFO - ^ 07:18:11 INFO - 1 warning generated. 07:18:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:18:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:11 INFO - Successfully installed psutil 07:18:11 INFO - Cleaning up... 07:18:11 INFO - Return code: 0 07:18:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:11 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faa8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc083cd46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:11 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.0.0'] in /builds/slave/test/build 07:18:11 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.0.0 07:18:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:18:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:11 INFO - 'HOME': '/Users/cltbld', 07:18:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:11 INFO - 'LOGNAME': 'cltbld', 07:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:11 INFO - 'MOZ_NO_REMOTE': '1', 07:18:11 INFO - 'NO_EM_RESTART': '1', 07:18:11 INFO - 'PAGER': '/bin/cat', 07:18:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:11 INFO - 'PWD': '/builds/slave/test', 07:18:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:11 INFO - 'SHELL': '/bin/bash', 07:18:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:18:11 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:18:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:11 INFO - 'USER': 'cltbld', 07:18:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:11 INFO - 'XPC_FLAGS': '0x0', 07:18:11 INFO - 'XPC_SERVICE_NAME': '0', 07:18:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:18:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:18:14 INFO - Installing collected packages: mozsystemmonitor 07:18:14 INFO - Running setup.py install for mozsystemmonitor 07:18:14 INFO - Successfully installed mozsystemmonitor 07:18:14 INFO - Cleaning up... 07:18:14 INFO - Return code: 0 07:18:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:14 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 0x10faa8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc083cd46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:18:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:18:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:14 INFO - 'HOME': '/Users/cltbld', 07:18:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:14 INFO - 'LOGNAME': 'cltbld', 07:18:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:14 INFO - 'MOZ_NO_REMOTE': '1', 07:18:14 INFO - 'NO_EM_RESTART': '1', 07:18:14 INFO - 'PAGER': '/bin/cat', 07:18:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:14 INFO - 'PWD': '/builds/slave/test', 07:18:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:14 INFO - 'SHELL': '/bin/bash', 07:18:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:18:14 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:18:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:14 INFO - 'USER': 'cltbld', 07:18:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:14 INFO - 'XPC_FLAGS': '0x0', 07:18:14 INFO - 'XPC_SERVICE_NAME': '0', 07:18:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:14 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:17 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:18 INFO - Downloading docopt-0.6.1.tar.gz 07:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:18 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:18 INFO - Running setup.py install for blobuploader 07:18:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:18 INFO - Running setup.py install for requests 07:18:19 INFO - Running setup.py install for docopt 07:18:19 INFO - Successfully installed blobuploader requests docopt 07:18:19 INFO - Cleaning up... 07:18:19 INFO - Return code: 0 07:18:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:19 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 0x10faa8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc083cd46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:18:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:19 INFO - 'HOME': '/Users/cltbld', 07:18:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:19 INFO - 'LOGNAME': 'cltbld', 07:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:19 INFO - 'MOZ_NO_REMOTE': '1', 07:18:19 INFO - 'NO_EM_RESTART': '1', 07:18:19 INFO - 'PAGER': '/bin/cat', 07:18:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:19 INFO - 'PWD': '/builds/slave/test', 07:18:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:19 INFO - 'SHELL': '/bin/bash', 07:18:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:18:19 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:18:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:19 INFO - 'USER': 'cltbld', 07:18:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:19 INFO - 'XPC_FLAGS': '0x0', 07:18:19 INFO - 'XPC_SERVICE_NAME': '0', 07:18:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:19 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-SnImlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:19 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-7a1SoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:19 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-_vyqTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:19 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-ed5rE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:19 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-WENEmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-R465N1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-59owbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-4fP8oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-59dH4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-yMj7qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-niyv5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-kFcKd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-lGBtB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-VFTT0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:20 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-fi5iSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:21 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-SRqPPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:21 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-OwpXsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:21 INFO - Running setup.py install for manifestparser 07:18:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:21 INFO - Running setup.py install for mozcrash 07:18:21 INFO - Running setup.py install for mozdebug 07:18:21 INFO - Running setup.py install for mozdevice 07:18:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:21 INFO - Running setup.py install for mozfile 07:18:22 INFO - Running setup.py install for mozhttpd 07:18:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:22 INFO - Running setup.py install for mozinfo 07:18:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:18:22 INFO - Running setup.py install for mozInstall 07:18:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:22 INFO - Running setup.py install for mozleak 07:18:22 INFO - Running setup.py install for mozlog 07:18:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:22 INFO - Running setup.py install for moznetwork 07:18:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:22 INFO - Running setup.py install for mozprocess 07:18:23 INFO - Running setup.py install for mozprofile 07:18:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:23 INFO - Running setup.py install for mozrunner 07:18:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:23 INFO - Running setup.py install for mozscreenshot 07:18:23 INFO - Running setup.py install for moztest 07:18:23 INFO - Running setup.py install for mozversion 07:18:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:23 INFO - Cleaning up... 07:18:23 INFO - Return code: 0 07:18:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:23 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 0x10faa8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc083cd46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:18:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:23 INFO - 'HOME': '/Users/cltbld', 07:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:23 INFO - 'LOGNAME': 'cltbld', 07:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:23 INFO - 'MOZ_NO_REMOTE': '1', 07:18:23 INFO - 'NO_EM_RESTART': '1', 07:18:23 INFO - 'PAGER': '/bin/cat', 07:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:23 INFO - 'PWD': '/builds/slave/test', 07:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:23 INFO - 'SHELL': '/bin/bash', 07:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:18:23 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:23 INFO - 'USER': 'cltbld', 07:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:23 INFO - 'XPC_FLAGS': '0x0', 07:18:23 INFO - 'XPC_SERVICE_NAME': '0', 07:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:24 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-jVhUic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:24 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-Hdx5YF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:24 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-Sk2g3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:24 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-g7YAhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:24 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-4Rh7Sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:24 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-uK_azH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:24 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-Izr0Ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:24 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-12L7C3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:24 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-aBKLCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:25 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-Ib0tAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:25 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-ZdlwW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:25 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-plILmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:25 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-blVNqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:25 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-xstpRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:25 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-683_ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:25 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-V1etmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:25 INFO - Running setup.py (path:/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/pip-mdXB28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:28 INFO - Downloading blessings-1.6.tar.gz 07:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:28 INFO - Installing collected packages: blessings 07:18:28 INFO - Running setup.py install for blessings 07:18:28 INFO - Successfully installed blessings 07:18:28 INFO - Cleaning up... 07:18:28 INFO - Return code: 0 07:18:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:28 INFO - Reading from file tmpfile_stdout 07:18:28 INFO - Current package versions: 07:18:28 INFO - blessings == 1.6 07:18:28 INFO - blobuploader == 1.2.4 07:18:28 INFO - docopt == 0.6.1 07:18:28 INFO - manifestparser == 1.1 07:18:28 INFO - mozInstall == 1.12 07:18:28 INFO - mozcrash == 0.17 07:18:28 INFO - mozdebug == 0.1 07:18:28 INFO - mozdevice == 0.48 07:18:28 INFO - mozfile == 1.2 07:18:28 INFO - mozhttpd == 0.7 07:18:28 INFO - mozinfo == 0.9 07:18:28 INFO - mozleak == 0.1 07:18:28 INFO - mozlog == 3.1 07:18:28 INFO - moznetwork == 0.27 07:18:28 INFO - mozprocess == 0.22 07:18:28 INFO - mozprofile == 0.28 07:18:28 INFO - mozrunner == 6.11 07:18:28 INFO - mozscreenshot == 0.1 07:18:28 INFO - mozsystemmonitor == 0.0 07:18:28 INFO - moztest == 0.7 07:18:28 INFO - mozversion == 1.4 07:18:28 INFO - psutil == 3.1.1 07:18:28 INFO - requests == 1.2.3 07:18:28 INFO - wsgiref == 0.1.2 07:18:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:28 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 0x10faa8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc083cd46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:28 INFO - 'HOME': '/Users/cltbld', 07:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:28 INFO - 'LOGNAME': 'cltbld', 07:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:28 INFO - 'MOZ_NO_REMOTE': '1', 07:18:28 INFO - 'NO_EM_RESTART': '1', 07:18:28 INFO - 'PAGER': '/bin/cat', 07:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:28 INFO - 'PWD': '/builds/slave/test', 07:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:28 INFO - 'SHELL': '/bin/bash', 07:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:18:28 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:28 INFO - 'USER': 'cltbld', 07:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:28 INFO - 'XPC_FLAGS': '0x0', 07:18:28 INFO - 'XPC_SERVICE_NAME': '0', 07:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:18:29 INFO - Cleaning up... 07:18:29 INFO - Return code: 0 07:18:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faa8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc083cd46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:18:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:29 INFO - 'HOME': '/Users/cltbld', 07:18:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:29 INFO - 'LOGNAME': 'cltbld', 07:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:29 INFO - 'MOZ_NO_REMOTE': '1', 07:18:29 INFO - 'NO_EM_RESTART': '1', 07:18:29 INFO - 'PAGER': '/bin/cat', 07:18:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:29 INFO - 'PWD': '/builds/slave/test', 07:18:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:29 INFO - 'SHELL': '/bin/bash', 07:18:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:18:29 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:18:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:29 INFO - 'USER': 'cltbld', 07:18:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:29 INFO - 'XPC_FLAGS': '0x0', 07:18:29 INFO - 'XPC_SERVICE_NAME': '0', 07:18:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:29 INFO - Downloading/unpacking jsonschema 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:18:31 INFO - Downloading/unpacking functools32 (from jsonschema) 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18:32 INFO - Downloading functools32-3.2.3-2.tar.gz 07:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:32 INFO - warning: no files found matching '*.txt' 07:18:32 INFO - no previously-included directories found matching 'build' 07:18:32 INFO - no previously-included directories found matching 'dist' 07:18:32 INFO - no previously-included directories found matching '.git*' 07:18:32 INFO - Installing collected packages: jsonschema, functools32 07:18:32 INFO - Running setup.py install for functools32 07:18:32 INFO - warning: no files found matching '*.txt' 07:18:32 INFO - no previously-included directories found matching 'build' 07:18:32 INFO - no previously-included directories found matching 'dist' 07:18:32 INFO - no previously-included directories found matching '.git*' 07:18:32 INFO - Successfully installed jsonschema functools32 07:18:32 INFO - Cleaning up... 07:18:32 INFO - Return code: 0 07:18:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:32 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faa8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc083cd46a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:32 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', 'flake8'] in /builds/slave/test/build 07:18:32 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 flake8 07:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:32 INFO - 'HOME': '/Users/cltbld', 07:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:32 INFO - 'LOGNAME': 'cltbld', 07:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:32 INFO - 'MOZ_NO_REMOTE': '1', 07:18:32 INFO - 'NO_EM_RESTART': '1', 07:18:32 INFO - 'PAGER': '/bin/cat', 07:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:32 INFO - 'PWD': '/builds/slave/test', 07:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:32 INFO - 'SHELL': '/bin/bash', 07:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:18:32 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:32 INFO - 'USER': 'cltbld', 07:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:32 INFO - 'XPC_FLAGS': '0x0', 07:18:32 INFO - 'XPC_SERVICE_NAME': '0', 07:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:32 INFO - Downloading/unpacking flake8 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:18:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:18:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:18:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:18:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:18:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:18:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:18:36 INFO - no previously-included directories found matching 'docs/_build' 07:18:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:18:36 INFO - Running setup.py install for pep8 07:18:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:18:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:18:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:18:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:18:37 INFO - no previously-included directories found matching 'docs/_build' 07:18:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:18:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:18:37 INFO - Cleaning up... 07:18:37 INFO - Return code: 0 07:18:37 INFO - Running post-action listener: _resource_record_post_action 07:18:37 INFO - Running post-action listener: _start_resource_monitoring 07:18:37 INFO - Starting resource monitoring. 07:18:37 INFO - ##### 07:18:37 INFO - ##### Finished create-virtualenv step (success) 07:18:37 INFO - ##### 07:18:37 INFO - ##### 07:18:37 INFO - ##### Running install step. 07:18:37 INFO - ##### 07:18:37 INFO - Running pre-action listener: _resource_record_pre_action 07:18:37 INFO - Running main action method: install 07:18:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:37 INFO - Reading from file tmpfile_stdout 07:18:37 INFO - Detecting whether we're running mozinstall >=1.0... 07:18:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:37 INFO - Reading from file tmpfile_stdout 07:18:37 INFO - Output received: 07:18:37 INFO - Usage: mozinstall [options] installer 07:18:37 INFO - Options: 07:18:37 INFO - -h, --help show this help message and exit 07:18:37 INFO - -d DEST, --destination=DEST 07:18:37 INFO - Directory to install application into. [default: 07:18:37 INFO - "/builds/slave/test"] 07:18:37 INFO - --app=APP Application being installed. [default: firefox] 07:18:37 INFO - mkdir: /builds/slave/test/build/application 07:18:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:19:10 INFO - Reading from file tmpfile_stdout 07:19:10 INFO - Output received: 07:19:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:19:10 INFO - Running post-action listener: _resource_record_post_action 07:19:10 INFO - ##### 07:19:10 INFO - ##### Finished install step (success) 07:19:10 INFO - ##### 07:19:10 INFO - ##### 07:19:10 INFO - ##### Running run-tests step. 07:19:10 INFO - ##### 07:19:10 INFO - Running pre-action listener: _resource_record_pre_action 07:19:10 INFO - Running main action method: run_tests 07:19:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:10 INFO - 2016-05-10 07:19:10.995 screenresolution[6251:102864] starting screenresolution argv=screenresolution get 07:19:10 INFO - 2016-05-10 07:19:10.998 screenresolution[6251:102864] Display 0: 1600x1200x32@60 07:19:11 INFO - 2016-05-10 07:19:11.004 screenresolution[6252:102867] starting screenresolution argv=screenresolution list 07:19:11 INFO - Available Modes on Display 0 07:19:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:11 INFO - Intel Iris: 07:19:11 INFO - Chipset Model: Intel Iris 07:19:11 INFO - Type: GPU 07:19:11 INFO - Bus: Built-In 07:19:11 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:11 INFO - Vendor: Intel (0x8086) 07:19:11 INFO - Device ID: 0x0a2e 07:19:11 INFO - Revision ID: 0x0009 07:19:11 INFO - Displays: 07:19:11 INFO - Display: 07:19:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:11 INFO - Display Serial Number: 1600x1200 60 07:19:11 INFO - Main Display: Yes 07:19:11 INFO - Mirror: Off 07:19:11 INFO - Online: Yes 07:19:11 INFO - Rotation: Supported 07:19:11 INFO - Return code: 0 07:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:11 INFO - Python 2.7.3 07:19:11 INFO - Return code: 0 07:19:11 INFO - grabbing minidump binary from tooltool 07:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:11 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f94cd30>, '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 0x10f770db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f943b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:11 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:19:11 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:19:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:11 INFO - Return code: 0 07:19:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:19:12 INFO - Return code: 0 07:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yFxmtyRx4v/Render', 07:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:12 INFO - 'HOME': '/Users/cltbld', 07:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:12 INFO - 'LOGNAME': 'cltbld', 07:19:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:12 INFO - 'MOZ_NO_REMOTE': '1', 07:19:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:12 INFO - 'NO_EM_RESTART': '1', 07:19:12 INFO - 'PAGER': '/bin/cat', 07:19:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:12 INFO - 'PWD': '/builds/slave/test', 07:19:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:12 INFO - 'SHELL': '/bin/bash', 07:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners', 07:19:12 INFO - 'TMPDIR': '/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/', 07:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:12 INFO - 'USER': 'cltbld', 07:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:12 INFO - 'XPC_FLAGS': '0x0', 07:19:12 INFO - 'XPC_SERVICE_NAME': '0', 07:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:19:12 INFO - mozversion application_buildid: 20160510043819 07:19:12 INFO - mozversion application_changeset: 1ded8982a100a4b703be202f8d4d303fcf34b316 07:19:12 INFO - mozversion application_display_name: Nightly 07:19:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:12 INFO - mozversion application_name: Firefox 07:19:12 INFO - mozversion application_remotingname: firefox 07:19:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:19:12 INFO - mozversion application_vendor: Mozilla 07:19:12 INFO - mozversion application_version: 49.0a1 07:19:12 INFO - mozversion platform_buildid: 20160510043819 07:19:12 INFO - mozversion platform_changeset: 1ded8982a100a4b703be202f8d4d303fcf34b316 07:19:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:19:12 INFO - mozversion platform_version: 49.0a1 07:19:12 INFO - using testdate: 1462889952 07:19:12 INFO - actual date: 1462889952 07:19:12 INFO - starting webserver on 'localhost:56996' 07:19:12 INFO - SUITE-START | Running 2 tests 07:19:12 INFO - TEST-START | dromaeo_css 07:19:12 INFO - operating with platform_type : mac_ 07:19:12 INFO - Initialising browser for dromaeo_css test... 07:19:12 INFO - TEST-INFO | started process 6270 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpc127KW/profile http://localhost:56996/getInfo.html) 07:19:15 INFO - PROCESS | 6270 | __metrics Screen width/height:1600/1200 07:19:15 INFO - PROCESS | 6270 | colorDepth:24 07:19:15 INFO - PROCESS | 6270 | Browser inner width/height: 1024/689 07:19:15 INFO - PROCESS | 6270 | __metrics 07:19:15 INFO - PROCESS | 6270 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:19:15 ERROR - PROCESS | 6270 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:19:16 INFO - TEST-INFO | 6270: exit 0 07:19:16 INFO - Browser initialized. 07:19:16 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:16 INFO - TEST-INFO | started process 6273 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpc127KW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:28 INFO - PROCESS | 6273 | RSS: Main: 238755840 07:19:28 INFO - PROCESS | 6273 | 07:20:19 INFO - PROCESS | 6273 | 1462890019674 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:22:29 INFO - PROCESS | 6273 | Cycle 1(1): loaded http://localhost:56996/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56996/tests/dromaeo/cssquery-ext.html) 07:22:31 INFO - PROCESS | 6273 | RSS: Main: 343527424 07:22:31 INFO - PROCESS | 6273 | 07:25:34 INFO - PROCESS | 6273 | Cycle 1(1): loaded http://localhost:56996/tests/dromaeo/cssquery-ext.html (next: http://localhost:56996/tests/dromaeo/cssquery-jquery.html) 07:25:34 INFO - PROCESS | 6273 | RSS: Main: 375586816 07:25:34 INFO - PROCESS | 6273 | 07:28:37 INFO - PROCESS | 6273 | Cycle 1(1): loaded http://localhost:56996/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56996/tests/dromaeo/cssquery-mootools.html) 07:28:37 INFO - PROCESS | 6273 | RSS: Main: 368746496 07:28:37 INFO - PROCESS | 6273 | 07:31:40 INFO - PROCESS | 6273 | Cycle 1(1): loaded http://localhost:56996/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56996/tests/dromaeo/cssquery-prototype.html) 07:31:41 INFO - PROCESS | 6273 | RSS: Main: 367046656 07:31:41 INFO - PROCESS | 6273 | 07:34:44 INFO - PROCESS | 6273 | Cycle 1(1): loaded http://localhost:56996/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56996/tests/dromaeo/cssquery-yui.html) 07:34:45 INFO - PROCESS | 6273 | RSS: Main: 331956224 07:34:45 INFO - PROCESS | 6273 | 07:37:48 INFO - PROCESS | 6273 | Cycle 1(1): loaded http://localhost:56996/tests/dromaeo/cssquery-yui.html (next: http://localhost:56996/tests/dromaeo/cssquery-dojo.html) 07:37:48 INFO - PROCESS | 6273 | RSS: Main: 393707520 07:37:48 INFO - PROCESS | 6273 | 07:37:48 INFO - PROCESS | 6273 | __start_tp_report 07:37:48 INFO - PROCESS | 6273 | _x_x_mozilla_page_load 07:37:48 INFO - PROCESS | 6273 | _x_x_mozilla_page_load_details 07:37:48 INFO - PROCESS | 6273 | |i|pagename|runs| 07:37:48 INFO - PROCESS | 6273 | |0;dojo.html;3230.772306523521;3429.725621950244;3433.17603775094;3437.364087643786;3509.245512214873;5626.184203290521;5639.900219457208;5680.687562184078;5767.567432442568;5772.365039845614;5289.603279754015;5447.774250793579;5748.885022299552;5839.357670656224;5876.11858221266;5140.820071297505;5159.303494028296;5229.60777941654;5233.764480598382;5326.6005049621235;5301.416844147141;5330.626856120073;5339.092354299778;5346.492083252104;5397.676139431627;5147.665401748893;5183.688978661293;5199.428062913076;5253.369595648508;5261.815836445725;5152.6650767699975;5164.845054648366;5246.973765131188;5247.895042099175;5270.446603106695;5129.717865517395;5132.3327967364;5157.149070403379;5190.7404629768735;5194.6883187008525;5697.1739097831;5698.572607054446;5709.972205003098;5726.484616384504;5738.0532212184935;6091.208142941434;6135.815925522242;6177.536684748662;6230.345813689588;6250.843728906768;6269.811905642838;6291.245050593912;6312.147860038928;6317.6209427434505;6322.083297921821;5849.327327357366;5912.733926973297;5915.645061296336;5922.881542369171;5945.7621695132575;5668.688222147739;6029.849253768691;6189.938100618936;6197.628142311476;6273.529485288622;6131.448169664752;6190.0405437157315;6208.689565521796;6212.347703491158;6215.875682486416;5295.576353891679;6272.843178920472;6296.5592408531875;6299.023651334051;6310.684465776692;5075.847724568269;6122.184701511125;6151.9234134026365;6183.752649893954;6201.224846894138;6254.843628909222;6299.811005669837;6305.842353941188;6310.242770867525;6321.273053598803;4938.4320803107385;4943.876403089952;4950.43070046942;4963.329950456642;4971.328870602422;4352.69531132824;4399.340098985178;4453.421055262795;4464;4468.888277793081;5800.477956983892;5808.970955145282;5822.650640961556;5827.88344233113;5846.678432686158;5259.552938000313;6267.874642507216;6270.153529273581;6284.685765711696;6301.936980630227;5572.5263352614675;5649.943500564942;5694.829155125353;5709.65742055478;5751.281089863767;5419.403865574784;5421.349438067457;5428.9185662215095;5439.592030597721;5447.863803404867;6394.104825324458;6401.0078437841275;6402.967985160044;6403.231612206565;6423.775167869289;6157.0456579230295;6169.2288463942;6243.562950593415;6253.749850005949;6260.373962603885;6327.620342779448;6330.9050364245495;6343.55595108338;6343.682815859096;6357.713902874474;6154.661493617894;6155.784547540815;6178.62928224308;6180.81457556274;6192.256929168529;6061;6077.696115194134;6159.784407545714;6212.47193988516;6249.937500624936;6125.724342404514;6155.6614386209185;6158.137860699595;6160.753569857335;6167.383261673796;5469.453054694499;6114.960456722279;6125.264968203844;6148.815535533941;6168.19813424252;5833.212516310414;5923.46688797993;5927.8221653350465;5972.790952316648;5992.43071908168;6134.079888016833;6136.9873970794315;6152.723127459365;6158.938410615837;6168.845778855385;5909.556783241188;5941.910871336847;5969.3732158122775;6019.759209631566;6035 07:37:48 INFO - PROCESS | 6273 | |1;ext.html;491.9139150648636;493.6273113799079;507.337924009168;516.8294462827267;524.339332441124;30850.84574577127;42927.78536107319;43986;44015.11969760603;44176.337354939664;44327.335089973596;44685;44790;44793.32810007853;44848.3272750909;8415.621297041647;9565.617375304997;9765.804683906317;9774.120329170373;9779.804403911918;8532.488050716976;11443.713907152347;11636.360000199986;11684;11791.823122653168;11599.478023488964;11653.766924661502;11663.883361166407;11671.299722016705;11703.5903743369;8484.830303393897;11486.885131148749;11550.71123221922;11669.241499302603;11694.883051169507;11284.661460156209;11416.771664566662;11434;11439.428028598537;11447.885521144848;40573.594264057276;40872.795636021925;40932.97667558305;41042.76871693854;41100.38349424761;3232;3280.2947366316357;3335.466325387926;3725.2549490102087;3927.0967677434064;28609.713902860913;28895.104895104894;29303.2674183145;29349.26626834325;29467;2672.318558767521;2716.375233696241;2723.6459260296233;2733.26201925479;2792.706765789583;469886;474260;476190.619046906;476386;476885.9999999965;64426.6778666113;64675.02987455192;64950;64983.67508162523;65047;37247.25505489875;38444;38473.42289865654;39304.80347598244;67937;16783.58041048959;16823.15884205785;16872.325106995584;16902.661946761244;16905.408310709066;12112.515499380102;12301.56944506956;13026.609201723963;13146.276954767389;13201.537946171838;20250.493737656678;20418.591628167353;20462.181512739324;20471;20536.58926821485;7200.927990720131;7902.525848448997;7934.127246002935;7937.365010799238;7961.1242763295995;799.936005119589;977.8924318324979;1071.0628200324716;1089.2593036734938;1122.3041714137287;29378.11865644034;29580.852095739385;30049.699503005126;30156.54765178524;30225.546616800766;1080.2546243092397;1112.9499172537255;1160.1994623709613;1219.71336735867;1221.7477085986934;15439.228038598025;15441.382344706311;15467;15519.689606207812;15877.285522151531;663265;669316;672901;673410;675879;51072.744636276584;62524;62538.374616253255;62586;62747;1310.2073245686413;1443.3288520837862;1459.992700036493;1493.6116609681344;1497.9700405992037;30358.69641303559;30449.238769030064;30551.388972220877;31115.844420778656;31167.532487013166;52240.21639675484;52590.68523286949;52674;52826.94346112979;52951.94096118133;408120;479439;481322;485273;488033;22841;24107.27678169658;24942.62586061247;25007.874645641354;25026;37565;45991.3101303474;46339;46634.300485493426;46912.7654361726;37949.466948840956;38088;38297.425538616386;38647;39591.80204098961;37320;37635.62364376431;37682.43476347912;38481.807590961864;38618.80690596529;37639.81180094082;37941.430878537394;38435.80782096072;38727.225455491294;38759.806200968815;30457;31043;31182;31279.68720312859;31313.53029704511;24483.510329793662;24519.26442206737;24881.377965551004;25105;25163.344781215535 07:37:48 INFO - PROCESS | 6273 | |2;jquery.html;335.8639750900886;390.8690588652801;397.49121124960044;410.97123201375916;420.73493698969645;19364.90317548412;20693.482662933435;21667;21702.457438564023;21835.344939651797;20160;21034.579308413824;21297.893510532464;21314.680279795815;21502.247421340257;5179.181525723841;5321.36143662761;5503.972480137613;5506.091494903336;5527.474889885474;3865.5168103987003;4059.8579049733266;4060.736052156615;4074.2666320062376;5338.946610533903;5677.17680936264;6012.639241645494;6014.8195554133445;6034.5172386209;6042.274927008765;5924.674142922137;6330.303666596671;6520.608763474207;6545.247296560861;6551.541392102531;3682.097886017916;5435.891282174374;5780.364159942403;5790.305163380379;5797.188393624896;12425.378731063502;13671.86328136716;14061.859381406259;14079.788803167961;14098;2090.080364639554;2142.6143294207036;2179.836512261587;2180.4548862784304;2291.0950174680906;10143.391396516232;10181.440020798853;10365.740856478573;12813.935930320382;13642.386092625855;1629.4215553478464;1645.539249010279;1666.4334126396961;1728.2136627834304;1796.6676164909534;120068.39965800114;126978.3651081748;127131.99999999907;128124.35937820344;129102.3544882279;9823.459709715891;11582.305061696325;11657.300561966309;11718.414079296;11722.355270460035;15344.309506072253;15409.075455472708;15436.45972390961;15456.613584660248;15483.5354939352;7275.454340924346;8835.74688454453;8893.066228045962;8957.731268061969;8998.235150012191;8775.605097770616;8817.206451419275;8823.117688231125;8858.149962674865;8968.910310896938;3633.6184700606586;4266.87199384019;4597.08058388317;4630.189716799547;4696.577308042224;1571.2693597477228;1725.5168552805235;1773.7428072929354;1900.9049547522568;1967.9704804427945;921.8571121476182;948.2508818033814;950.2825366847985;975.6926568131016;994.7264502261792;19342.10526315795;19580.216791328472;20037.998100094937;20947.37157885266;21093.89453052725;884.2844880930974;895.6686026170229;900.8243392538392;932.7668082979255;953.8378475659155;10783.352998820095;11168.553257869757;11281.097512199005;11300.660980170444;11304.773904521864;607534;610052;618837;623371;623831;135147.32426337805;135170.6482935158;138238;138815;144356;990.4601991914424;996.4519514267124;1017.0338178730236;1018.7045756730465;1020.2297265564451;18566.350177743716;18909.43271701851;18990.43028709141;19064.904675476533;19089.904550477713;50207;52982;54124.729376352865;54464;54994.72502637461;80713;81036.18963810286;82066.58966705129;82208.58895705722;82553.58723206585;8143.837123257384;8243;8309.916900830915;8369;8440.915590844259;7153.964230178815;9796.9020309796;11842.171048026556;11843;12357.567485137977;6376.489880809617;9810.85283720759;10594.205434592282;10916.890831091587;11490.827637585277;10792.784144317227;10994.505247264053;11080.279781814157;11098.667039988813;11220.2145849793;10367.963203679572;11111.055560277468;11190.32858028521;11224.102071834397;11405.543778248777;8141.43009989295;8204.54874981858;8326.417150799629;8417.747467575982;8488;8334.333253339597;10526.052655261063;10897.019268265894;11266.774664506827;11548.711282217675 07:37:48 INFO - PROCESS | 6273 | |3;mootools.html;1416.8158139441884;1600.11993403628;1634.8773841961854;1872.9719054214181;1998.9800101998985;7977.0826354969195;8856.77858053549;9096.954515227431;9112.997570267273;9236.815263694722;8778.122187781235;9042.276617870571;9061.637534498612;9062.320325975561;9069.591868365922;2155.8490905636613;2241.540484200735;2402.459446624513;2414.927552173433;2452.0559584559896;2465.6794616699895;2506.6616006839026;2506.8997240110375;2508.673872396586;2512.849229046263;2412.6984126984125;2470.3083136721657;2470.419451428913;2476.702795664523;2478.39279376553;2121.533262682215;2295.8392912496133;2367.0886708617136;2440.2313271319654;2447.2535876557645;2425.9150929717375;2429.781319681237;2466.9259922202364;2467.7532246775177;2468.938276543101;9111.81776364469;9191.35660503765;9197.21823644991;9218.170364667212;9221.35450518464;5455.372632147314;5632.549396048306;5637.943620563783;5638.182463542805;5639.887202255973;3585.533880595532;3595.6224596417524;3640.290143422034;3665.7433979621437;3682.447632855093;914.2092090341873;1026.1329176845554;1028.3213079367656;1030.5311083457009;1045.3309881675734;12331.013518918466;12359.949404300645;12362.629121126382;12391.256524608552;12396.256224626552;4435.112977404532;4843.75781210938;4855.198892182821;4994.9750251248515;4999.950000499948;10322.638707645196;10337.328073675188;10338.483075846178;10347.896521034843;10352.327098738708;5562.443755624486;5599.216109744639;5600.85997850057;5626.043572592669;5641.12562552805;4494.348319493655;4683.742394168354;4918.827841025547;5013.423456302499;5018.824341148043;4025.705849431667;4060.8020633913065;4126.566710495415;4163.271427500175;4163.83344666216;1337.7859542473157;1349.9605303916126;1354;1357.7216670582507;1372.4715984346099;832.6669332267021;893.5264309915755;922.9400088994194;923.3997901364032;970.8106919150839;4889.828855990023;5713.6857472838565;5930.762769489172;6045;6059.6364218147055;457.7802654725684;463.54109431663085;465.34851208307964;972.2222222222194;1028.4189432970347;3214.7106760391675;3993;4026.315526360526;4066.9796651016554;4072.857449989295;10878.945605272082;11357.716057098474;11520.308781473139;11535.423228838525;11535.884641153649;9709.36889102206;11207.887921120848;11230.989210971053;11266.324020558794;11480;8320.459170154041;8537.658493660185;8550.700725474579;8557.657693692183;8688.435251708745;5818.301803783404;6007.098935159761;6103.877922441615;6107.328193898578;6117.694115294128;10114.494275286059;10127.392356458637;10293.948530257301;10308.484575771332;10352.17182625373;10002.44986525748;10516.263861529926;10573.207009474474;10757.408342540923;10835.349879007284;7151.7854464366155;7387.852242955219;7404.148522919933;7492.925070749223;7678.1170165431695;8578.485290882567;8688;8699.217070463688;8765.78085547841;8808.779780505538;7548.396128309612;7719.112302085332;7730.226977302224;7813;7901.407394445325;7502.137254215835;7552.169261381354;7575.431842611936;7657.961710191414;7813.14055453911;7144.67848946809;7241.891371629325;7242.637868106679;7254.455915806441;7256.383207427428;4054.8175332109536;4886.511348865085;4973.35346404965;5004.699718016931;5145.125328694206;4941;4997.550220480174;5045.621578381562;5103.489651034867;5268.710220937885 07:37:48 INFO - PROCESS | 6273 | |4;prototype.html;298.63566448932306;308.77922285565825;319.1192309226534;323.5809626533639;327.50056164349365;4820.349252850864;5168.043911876305;5441.56467482602;5445.346558412991;5500.312460942382;4950.3069570260195;5000.749363041413;5086.92369614456;5136.7928536793825;5215.400228973669;3554.3117019415763;3634.67287944085;3999.9600004000026;4033.97983010086;4091.4476545666403;3579.74941754076;3884.5726970033415;4062.5531191568903;4068.328725760261;4081.693872959525;3207.4386982278124;3732.8055022392846;3774.924501509968;3832.3485007548643;4051.858184963527;3286.3920174767745;3385.3737058644106;3973.761574305522;3974.2448934702456;4031.5363733170766;3373.3927892979254;3518.7480012791834;3521.9471707924404;3531.434970404723;3532.311199316134;4454.866354009385;4953.876153096201;5043.470435604246;5060.974695126538;5157.149070403379;4602.447706275235;4602.677812553123;4630.421197350332;4643.512431194709;4654.394928659286;5012.020252000476;5504.422035686275;5542.7782888684405;5546.639468434539;5552.86117847053;3464.4456886897974;3491.7555771096036;3649.981750091259;3825.196708691178;3910.022494376406;5536;5608.719564021823;5841.620294680874;5849.181114643953;5888.322842873039;3278.8032718036993;3564.4118720410843;3775.4903088082756;3965.8017099145504;4000.4799376081505;3490.8254587271044;3579.9105022374647;4109.178164367078;4109.835606575763;4132.152908653718;3867.9693161090654;4357.411749413788;4488.506264310923;4611.262198048364;4674.906501870011;4902.8774280643265;5148.433672296044;5154.561862241751;5544.889102217933;5553.972230138824;1130.1467392118898;1213.50246398976;1243.3410292544966;1364.740699267136;1380.7445622559858;735.0260904301822;774.666893235914;859.4886042804521;878.6748902905965;897.8742975983367;3782.678472329883;4273.97863010683;4289.919389096286;4298.699091063596;4301.0537681709975;4184.958150418518;4408.561322168714;4507.323901414814;4718.716876987323;4897.314375987365;4150.439690641725;4375.7374557526655;4665.486796452387;5136.58743845438;5195.986782577361;2800.81794683349;2952.2619345163707;2957.7190166934106;2980.64232292122;2989.312458134641;4825.734584597811;5936.228290322317;5968.7015649217365;5984.862876053536;5995.871465629726;4395.934060989088;4828.527299173786;5647.604667673223;5947.791827286024;5967.642781101527;5025.899482010339;5411.2965314508865;6025.8192254232445;6038.788642397583;6044.667543285162;4755.904881902412;4949.292494089534;5785.334686511105;5786.3056433228285;5832.62087964281;4735.928961065518;4928.482509336468;5233.136532472252;5638.971805140948;5703.08750599902;5314.760835762478;6016.338202797601;6016.42843929826;6087.417271509528;6117.96941015292;3736.8878933632036;3879.8836034919;3885.68914486846;3887.9416808747324;3890.513685789276;5670.432956704297;5685.829425117253;5724.942750572441;5785.859232084156;5811.651300921958;4776.349126234973;5245.659032162897;5608.775648973995;5655.915161272666;5703.543716502587;5246.947530524646;5583.385827559046;5625.212470254086;5636.943630563642;5650.63270887391;4570.88572785683;5099.69401835891;5303.655262408085;5459.208414779914;5528.91706624409;4806.4953179917;5295.655782374133;5471.467988963097;5516.172574113915;5532.944670553404;5194.818181363634;5482.835514934558;5486.119787111798;5486.506214440719;5503.422140675171 07:37:48 INFO - PROCESS | 6273 | |5;yui.html;577.7486793244939;727.923568025357;727.9963600181999;729.6534146280518;742.5878637356265;14688.339024743886;15244.161571113584;16255.837441625596;16824.24290906909;16828.915855420735;16012.119333436656;16094.436694715687;16178.919105404457;16180;16196.83803161968;726.1032624708481;748.4648476339416;926.5321012888498;936.9718908432724;940.5109343141562;903.7559858838109;935.443411170356;935.705252845355;939.685205456173;946.6781294359917;972.1348000279756;974.6101559376235;982.8378317577591;984.3011461862086;986.5659109991617;752.0336367767393;775.7711475114854;776.6582703610393;981.0875885426515;981.8821741391043;575.5021906051278;699.1400577289962;720.6720941971442;723.4646361692362;724.5978481942519;14815.70368592622;14866.925665371713;14881.70236595273;14895.180765057918;14928.477503287333;11944.940275298568;12009.399530023551;12177.269363838199;12254;12341;10811;10960.616378426717;11036;11438;11608;3980.5223373195113;3993.5008123984503;4005.499312585927;4121.29937910556;4139.9172016559805;59549;81920.59039704823;82198;82542.17457825405;82576.76134857982;9060.957989831253;9189.218916392048;9261.72214833556;9510.334276600572;9519.476428796344;20377.592448150954;20538.486537836376;20565.58868822615;20583.4854128645;20591.176352945713;7223.674934627849;7237.203907570163;7287.927120728831;7289.343959043712;7371.226021267689;3917.941230881539;3947.4276229946495;3976.6619837313574;4039.7172197946447;4058;3319.170207448138;3400.7449441292;3417.8461969211444;3421.4354631485526;3426.554547908756;634.80321100459;635.9841003974938;646.1019183335168;651.837040739815;660.9239937407164;475.1020571120585;577.3995045153077;605.5458406195354;612.4640939178219;622.539320902538;7932.167122452175;8217.876731849026;8271.503709777433;8384.958075209584;8407.41148119638;539.8623351045459;543.1933578635723;590.439082871274;609.7682880505469;610.3377835048933;3689.1883785567134;3767.56672982605;3914.784686842251;3970.9602903971168;3988.3219852625125;77256;78059;84414;90962.54518727364;93170;71968.64015679888;72576.91134632984;77774;81136.59431702804;82699;61299.38700613026;62986.74026519444;63759.36240637627;63980.20148488882;64266;7197.856042879009;7524.924750752422;7642.7325043623205;7644.617769111411;7730;41298.58701413067;41304.38043429411;41855.7907210462;42082.7895860531;42101.36847947222;56797.43202567921;58001.41998580129;58007.12989305248;59600.58393000778;60291.69854150877;10225.642102526375;10295.639652612123;10716.35701857891;11196.888031119584;11352.205345625727;12065.939670301943;12092.818607721067;12308.446119924803;12489.93755031219;12597.622071337875;8440.258686404064;11343.149263805082;11667.241629294409;12197.573084941985;12431.813522797345;10565;11258.38078905668;11911.76176476483;11914.344711040976;11998.940005299917;11145.554177832795;11287.379194144401;11359.886401135884;11774.234674746114;11798.997085247851;9355.672551460666;9461.290403219647;9496.905030949601;9506.90493095088;9778.55996480146;7591.127020392725;8026.277635012877;9379.531023448937;9718.173955213886;9738.61045558198 07:37:48 INFO - PROCESS | 6273 | __end_tp_report 07:37:48 INFO - PROCESS | 6273 | __start_cc_report 07:37:48 INFO - PROCESS | 6273 | _x_x_mozilla_cycle_collect,1149 07:37:48 INFO - PROCESS | 6273 | __end_cc_report 07:37:48 INFO - PROCESS | 6273 | __startTimestamp1462891068556__endTimestamp 07:37:48 INFO - PROCESS | 6273 | 07:37:48 INFO - PROCESS | 6273 | ------- Summary: start ------- 07:37:48 INFO - PROCESS | 6273 | Number of tests: 6 07:37:48 INFO - PROCESS | 6273 | 07:37:48 INFO - PROCESS | 6273 | [#0] dojo.html Cycles:165 Average:5742.07 Median:5927.82 stddev:636.55 (10.7%) stddev-sans-first:607.25 07:37:48 INFO - PROCESS | 6273 | Values: 3230.8 3429.7 3433.2 3437.4 3509.2 5626.2 5639.9 5680.7 5767.6 5772.4 5289.6 5447.8 5748.9 5839.4 5876.1 5140.8 5159.3 5229.6 5233.8 5326.6 5301.4 5330.6 5339.1 5346.5 5397.7 5147.7 5183.7 5199.4 5253.4 5261.8 5152.7 5164.8 5247.0 5247.9 5270.4 5129.7 5132.3 5157.1 5190.7 5194.7 5697.2 5698.6 5710.0 5726.5 5738.1 6091.2 6135.8 6177.5 6230.3 6250.8 6269.8 6291.2 6312.1 6317.6 6322.1 5849.3 5912.7 5915.6 5922.9 5945.8 5668.7 6029.8 6189.9 6197.6 6273.5 6131.4 6190.0 6208.7 6212.3 6215.9 5295.6 6272.8 6296.6 6299.0 6310.7 5075.8 6122.2 6151.9 6183.8 6201.2 6254.8 6299.8 6305.8 6310.2 6321.3 4938.4 4943.9 4950.4 4963.3 4971.3 4352.7 4399.3 4453.4 4464.0 4468.9 5800.5 5809.0 5822.7 5827.9 5846.7 5259.6 6267.9 6270.2 6284.7 6301.9 5572.5 5649.9 5694.8 5709.7 5751.3 5419.4 5421.3 5428.9 5439.6 5447.9 6394.1 6401.0 6403.0 6403.2 6423.8 6157.0 6169.2 6243.6 6253.7 6260.4 6327.6 6330.9 6343.6 6343.7 6357.7 6154.7 6155.8 6178.6 6180.8 6192.3 6061.0 6077.7 6159.8 6212.5 6249.9 6125.7 6155.7 6158.1 6160.8 6167.4 5469.5 6115.0 6125.3 6148.8 6168.2 5833.2 5923.5 5927.8 5972.8 5992.4 6134.1 6137.0 6152.7 6158.9 6168.8 5909.6 5941.9 5969.4 6019.8 6035.0 07:37:48 INFO - PROCESS | 6273 | 07:37:48 INFO - PROCESS | 6273 | [#1] ext.html Cycles:180 Average:67517.88 Median:20486.26 stddev:145988.00 (712.6%) stddev-sans-first:146310.79 07:37:48 INFO - PROCESS | 6273 | Values: 491.9 493.6 507.3 516.8 524.3 30850.8 42927.8 43986.0 44015.1 44176.3 44327.3 44685.0 44790.0 44793.3 44848.3 8415.6 9565.6 9765.8 9774.1 9779.8 8532.5 11443.7 11636.4 11684.0 11791.8 11599.5 11653.8 11663.9 11671.3 11703.6 8484.8 11486.9 11550.7 11669.2 11694.9 11284.7 11416.8 11434.0 11439.4 11447.9 40573.6 40872.8 40933.0 41042.8 41100.4 3232.0 3280.3 3335.5 3725.3 3927.1 28609.7 28895.1 29303.3 29349.3 29467.0 2672.3 2716.4 2723.6 2733.3 2792.7 469886.0 474260.0 476190.6 476386.0 476886.0 64426.7 64675.0 64950.0 64983.7 65047.0 37247.3 38444.0 38473.4 39304.8 67937.0 16783.6 16823.2 16872.3 16902.7 16905.4 12112.5 12301.6 13026.6 13146.3 13201.5 20250.5 20418.6 20462.2 20471.0 20536.6 7200.9 7902.5 7934.1 7937.4 7961.1 799.9 977.9 1071.1 1089.3 1122.3 29378.1 29580.9 30049.7 30156.5 30225.5 1080.3 1112.9 1160.2 1219.7 1221.7 15439.2 15441.4 15467.0 15519.7 15877.3 663265.0 669316.0 672901.0 673410.0 675879.0 51072.7 62524.0 62538.4 62586.0 62747.0 1310.2 1443.3 1460.0 1493.6 1498.0 30358.7 30449.2 30551.4 31115.8 31167.5 52240.2 52590.7 52674.0 52826.9 52951.9 408120.0 479439.0 481322.0 485273.0 488033.0 22841.0 24107.3 24942.6 25007.9 25026.0 37565.0 45991.3 46339.0 46634.3 46912.8 37949.5 38088.0 38297.4 38647.0 39591.8 37320.0 37635.6 37682.4 38481.8 38618.8 37639.8 37941.4 38435.8 38727.2 38759.8 30457.0 31043.0 31182.0 31279.7 31313.5 24483.5 24519.3 24881.4 25105.0 25163.3 07:37:48 INFO - PROCESS | 6273 | 07:37:48 INFO - PROCESS | 6273 | [#2] jquery.html Cycles:180 Average:35787.98 Median:12063.22 stddev:103356.67 (856.8%) stddev-sans-first:103612.34 07:37:48 INFO - PROCESS | 6273 | Values: 335.9 390.9 397.5 411.0 420.7 19364.9 20693.5 21667.0 21702.5 21835.3 20160.0 21034.6 21297.9 21314.7 21502.2 5179.2 5321.4 5504.0 5506.1 5527.5 3865.5 4059.9 4060.7 4074.3 5338.9 5677.2 6012.6 6014.8 6034.5 6042.3 5924.7 6330.3 6520.6 6545.2 6551.5 3682.1 5435.9 5780.4 5790.3 5797.2 12425.4 13671.9 14061.9 14079.8 14098.0 2090.1 2142.6 2179.8 2180.5 2291.1 10143.4 10181.4 10365.7 12813.9 13642.4 1629.4 1645.5 1666.4 1728.2 1796.7 120068.4 126978.4 127132.0 128124.4 129102.4 9823.5 11582.3 11657.3 11718.4 11722.4 15344.3 15409.1 15436.5 15456.6 15483.5 7275.5 8835.7 8893.1 8957.7 8998.2 8775.6 8817.2 8823.1 8858.1 8968.9 3633.6 4266.9 4597.1 4630.2 4696.6 1571.3 1725.5 1773.7 1900.9 1968.0 921.9 948.3 950.3 975.7 994.7 19342.1 19580.2 20038.0 20947.4 21093.9 884.3 895.7 900.8 932.8 953.8 10783.4 11168.6 11281.1 11300.7 11304.8 607534.0 610052.0 618837.0 623371.0 623831.0 135147.3 135170.6 138238.0 138815.0 144356.0 990.5 996.5 1017.0 1018.7 1020.2 18566.4 18909.4 18990.4 19064.9 19089.9 50207.0 52982.0 54124.7 54464.0 54994.7 80713.0 81036.2 82066.6 82208.6 82553.6 8143.8 8243.0 8309.9 8369.0 8440.9 7154.0 9796.9 11842.2 11843.0 12357.6 6376.5 9810.9 10594.2 10916.9 11490.8 10792.8 10994.5 11080.3 11098.7 11220.2 10368.0 11111.1 11190.3 11224.1 11405.5 8141.4 8204.5 8326.4 8417.7 8488.0 8334.3 10526.1 10897.0 11266.8 11548.7 07:37:48 INFO - PROCESS | 6273 | 07:37:48 INFO - PROCESS | 6273 | [#3] mootools.html Cycles:180 Average:5888.38 Median:4763.75 stddev:3365.44 (70.6%) stddev-sans-first:3358.11 07:37:48 INFO - PROCESS | 6273 | Values: 1416.8 1600.1 1634.9 1873.0 1999.0 7977.1 8856.8 9097.0 9113.0 9236.8 8778.1 9042.3 9061.6 9062.3 9069.6 2155.8 2241.5 2402.5 2414.9 2452.1 2465.7 2506.7 2506.9 2508.7 2512.8 2412.7 2470.3 2470.4 2476.7 2478.4 2121.5 2295.8 2367.1 2440.2 2447.3 2425.9 2429.8 2466.9 2467.8 2468.9 9111.8 9191.4 9197.2 9218.2 9221.4 5455.4 5632.5 5637.9 5638.2 5639.9 3585.5 3595.6 3640.3 3665.7 3682.4 914.2 1026.1 1028.3 1030.5 1045.3 12331.0 12359.9 12362.6 12391.3 12396.3 4435.1 4843.8 4855.2 4995.0 5000.0 10322.6 10337.3 10338.5 10347.9 10352.3 5562.4 5599.2 5600.9 5626.0 5641.1 4494.3 4683.7 4918.8 5013.4 5018.8 4025.7 4060.8 4126.6 4163.3 4163.8 1337.8 1350.0 1354.0 1357.7 1372.5 832.7 893.5 922.9 923.4 970.8 4889.8 5713.7 5930.8 6045.0 6059.6 457.8 463.5 465.3 972.2 1028.4 3214.7 3993.0 4026.3 4067.0 4072.9 10878.9 11357.7 11520.3 11535.4 11535.9 9709.4 11207.9 11231.0 11266.3 11480.0 8320.5 8537.7 8550.7 8557.7 8688.4 5818.3 6007.1 6103.9 6107.3 6117.7 10114.5 10127.4 10293.9 10308.5 10352.2 10002.4 10516.3 10573.2 10757.4 10835.3 7151.8 7387.9 7404.1 7492.9 7678.1 8578.5 8688.0 8699.2 8765.8 8808.8 7548.4 7719.1 7730.2 7813.0 7901.4 7502.1 7552.2 7575.4 7658.0 7813.1 7144.7 7241.9 7242.6 7254.5 7256.4 4054.8 4886.5 4973.4 5004.7 5145.1 4941.0 4997.6 5045.6 5103.5 5268.7 07:37:48 INFO - PROCESS | 6273 | 07:37:48 INFO - PROCESS | 6273 | [#4] prototype.html Cycles:180 Average:4428.49 Median:4827.13 stddev:1381.41 (28.6%) stddev-sans-first:1350.06 07:37:48 INFO - PROCESS | 6273 | Values: 298.6 308.8 319.1 323.6 327.5 4820.3 5168.0 5441.6 5445.3 5500.3 4950.3 5000.7 5086.9 5136.8 5215.4 3554.3 3634.7 4000.0 4034.0 4091.4 3579.7 3884.6 4062.6 4068.3 4081.7 3207.4 3732.8 3774.9 3832.3 4051.9 3286.4 3385.4 3973.8 3974.2 4031.5 3373.4 3518.7 3521.9 3531.4 3532.3 4454.9 4953.9 5043.5 5061.0 5157.1 4602.4 4602.7 4630.4 4643.5 4654.4 5012.0 5504.4 5542.8 5546.6 5552.9 3464.4 3491.8 3650.0 3825.2 3910.0 5536.0 5608.7 5841.6 5849.2 5888.3 3278.8 3564.4 3775.5 3965.8 4000.5 3490.8 3579.9 4109.2 4109.8 4132.2 3868.0 4357.4 4488.5 4611.3 4674.9 4902.9 5148.4 5154.6 5544.9 5554.0 1130.1 1213.5 1243.3 1364.7 1380.7 735.0 774.7 859.5 878.7 897.9 3782.7 4274.0 4289.9 4298.7 4301.1 4185.0 4408.6 4507.3 4718.7 4897.3 4150.4 4375.7 4665.5 5136.6 5196.0 2800.8 2952.3 2957.7 2980.6 2989.3 4825.7 5936.2 5968.7 5984.9 5995.9 4395.9 4828.5 5647.6 5947.8 5967.6 5025.9 5411.3 6025.8 6038.8 6044.7 4755.9 4949.3 5785.3 5786.3 5832.6 4735.9 4928.5 5233.1 5639.0 5703.1 5314.8 6016.3 6016.4 6087.4 6118.0 3736.9 3879.9 3885.7 3887.9 3890.5 5670.4 5685.8 5724.9 5785.9 5811.7 4776.3 5245.7 5608.8 5655.9 5703.5 5246.9 5583.4 5625.2 5636.9 5650.6 4570.9 5099.7 5303.7 5459.2 5528.9 4806.5 5295.7 5471.5 5516.2 5532.9 5194.8 5482.8 5486.1 5486.5 5503.4 07:37:48 INFO - PROCESS | 6273 | 07:37:48 INFO - PROCESS | 6273 | [#5] yui.html Cycles:180 Average:17341.83 Median:59946.14 stddev:23799.79 (39.7%) stddev-sans-first:23833.27 07:37:48 INFO - PROCESS | 6273 | Values: 577.7 727.9 728.0 729.7 742.6 14688.3 15244.2 16255.8 16824.2 16828.9 16012.1 16094.4 16178.9 16180.0 16196.8 726.1 748.5 926.5 937.0 940.5 903.8 935.4 935.7 939.7 946.7 972.1 974.6 982.8 984.3 986.6 752.0 775.8 776.7 981.1 981.9 575.5 699.1 720.7 723.5 724.6 14815.7 14866.9 14881.7 14895.2 14928.5 11944.9 12009.4 12177.3 12254.0 12341.0 10811.0 10960.6 11036.0 11438.0 11608.0 3980.5 3993.5 4005.5 4121.3 4139.9 59549.0 81920.6 82198.0 82542.2 82576.8 9061.0 9189.2 9261.7 9510.3 9519.5 20377.6 20538.5 20565.6 20583.5 20591.2 7223.7 7237.2 7287.9 7289.3 7371.2 3917.9 3947.4 3976.7 4039.7 4058.0 3319.2 3400.7 3417.8 3421.4 3426.6 634.8 636.0 646.1 651.8 660.9 475.1 577.4 605.5 612.5 622.5 7932.2 8217.9 8271.5 8385.0 8407.4 539.9 543.2 590.4 609.8 610.3 3689.2 3767.6 3914.8 3971.0 3988.3 77256.0 78059.0 84414.0 90962.5 93170.0 71968.6 72576.9 77774.0 81136.6 82699.0 61299.4 62986.7 63759.4 63980.2 64266.0 7197.9 7524.9 7642.7 7644.6 7730.0 41298.6 41304.4 41855.8 42082.8 42101.4 56797.4 58001.4 58007.1 59600.6 60291.7 10225.6 10295.6 10716.4 11196.9 11352.2 12065.9 12092.8 12308.4 12489.9 12597.6 8440.3 11343.1 11667.2 12197.6 12431.8 10565.0 11258.4 11911.8 11914.3 11998.9 11145.6 11287.4 11359.9 11774.2 11799.0 9355.7 9461.3 9496.9 9506.9 9778.6 7591.1 8026.3 9379.5 9718.2 9738.6 07:37:48 INFO - PROCESS | 6273 | -------- Summary: end -------- 07:37:48 INFO - PROCESS | 6273 | 07:37:49 INFO - TEST-INFO | 6273: exit 0 07:37:49 INFO - TEST-OK | dromaeo_css | took 1116602ms 07:37:49 INFO - TEST-START | kraken 07:37:49 INFO - operating with platform_type : mac_ 07:37:49 INFO - Initialising browser for kraken test... 07:37:49 INFO - TEST-INFO | started process 6276 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpbHppf5/profile http://localhost:56996/getInfo.html) 07:37:51 INFO - PROCESS | 6276 | __metrics Screen width/height:1600/1200 07:37:51 INFO - PROCESS | 6276 | colorDepth:24 07:37:51 INFO - PROCESS | 6276 | Browser inner width/height: 1024/689 07:37:51 INFO - PROCESS | 6276 | __metrics 07:37:51 INFO - PROCESS | 6276 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:37:51 ERROR - PROCESS | 6276 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:37:52 INFO - TEST-INFO | 6276: exit 0 07:37:52 INFO - Browser initialized. 07:37:52 INFO - Running cycle 1/1 for kraken test... 07:37:52 INFO - TEST-INFO | started process 6278 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/tmpbHppf5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:04 INFO - PROCESS | 6278 | RSS: Main: 238886912 07:38:04 INFO - PROCESS | 6278 | 07:38:06 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56996/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:07 INFO - PROCESS | 6278 | RSS: Main: 286621696 07:38:07 INFO - PROCESS | 6278 | 07:38:10 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56996/tests/kraken/driver.html?testName=audio-dft) 07:38:11 INFO - PROCESS | 6278 | RSS: Main: 295940096 07:38:11 INFO - PROCESS | 6278 | 07:38:14 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56996/tests/kraken/driver.html?testName=audio-fft) 07:38:15 INFO - PROCESS | 6278 | RSS: Main: 382447616 07:38:15 INFO - PROCESS | 6278 | 07:38:17 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56996/tests/kraken/driver.html?testName=audio-oscillator) 07:38:18 INFO - PROCESS | 6278 | RSS: Main: 284839936 07:38:18 INFO - PROCESS | 6278 | 07:38:20 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56996/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:21 INFO - PROCESS | 6278 | RSS: Main: 288309248 07:38:21 INFO - PROCESS | 6278 | 07:38:24 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56996/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:24 INFO - PROCESS | 6278 | RSS: Main: 326610944 07:38:24 INFO - PROCESS | 6278 | 07:38:27 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56996/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:28 INFO - PROCESS | 6278 | RSS: Main: 330579968 07:38:28 INFO - PROCESS | 6278 | 07:38:31 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56996/tests/kraken/driver.html?testName=json-parse-financial) 07:38:32 INFO - PROCESS | 6278 | RSS: Main: 327995392 07:38:32 INFO - PROCESS | 6278 | 07:38:33 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56996/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:34 INFO - PROCESS | 6278 | RSS: Main: 316203008 07:38:34 INFO - PROCESS | 6278 | 07:38:36 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56996/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:37 INFO - PROCESS | 6278 | RSS: Main: 322310144 07:38:37 INFO - PROCESS | 6278 | 07:38:39 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56996/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:40 INFO - PROCESS | 6278 | RSS: Main: 324501504 07:38:40 INFO - PROCESS | 6278 | 07:38:42 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:43 INFO - PROCESS | 6278 | RSS: Main: 329236480 07:38:43 INFO - PROCESS | 6278 | 07:38:46 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:47 INFO - PROCESS | 6278 | RSS: Main: 327098368 07:38:47 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | Cycle 1(1): loaded http://localhost:56996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56996/tests/kraken/driver.html?testName=ai-astar) 07:38:49 INFO - PROCESS | 6278 | RSS: Main: 373157888 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | __start_tp_report 07:38:49 INFO - PROCESS | 6278 | _x_x_mozilla_page_load 07:38:49 INFO - PROCESS | 6278 | _x_x_mozilla_page_load_details 07:38:49 INFO - PROCESS | 6278 | |i|pagename|runs| 07:38:49 INFO - PROCESS | 6278 | |0;ai-astar;111;107;114;112;112;113;112;114;113;112 07:38:49 INFO - PROCESS | 6278 | |1;audio-beat-detection;148;118;144;199;123;140;143;118;171;118 07:38:49 INFO - PROCESS | 6278 | |2;audio-dft;185;187;185;186;184;186;185;183;203;239 07:38:49 INFO - PROCESS | 6278 | |3;audio-fft;75;75;57;126;58;64;56;59;116;57 07:38:49 INFO - PROCESS | 6278 | |4;audio-oscillator;91;95;183;109;95;77;102;81;85;101 07:38:49 INFO - PROCESS | 6278 | |5;imaging-gaussian-blur;88;86;88;85;87;85;89;87;88;86 07:38:49 INFO - PROCESS | 6278 | |6;imaging-darkroom;94;94;94;94;107;146;94;97;93;94 07:38:49 INFO - PROCESS | 6278 | |7;imaging-desaturate;84;87;85;87;84;87;85;86;84;84 07:38:49 INFO - PROCESS | 6278 | |8;json-parse-financial;60;61;59;57;58;58;59;120;57;57 07:38:49 INFO - PROCESS | 6278 | |9;json-stringify-tinderbox;65;65;82;76;70;69;70;70;69;70 07:38:49 INFO - PROCESS | 6278 | |10;stanford-crypto-aes;84;82;85;84;85;86;83;85;85;86 07:38:49 INFO - PROCESS | 6278 | |11;stanford-crypto-ccm;136;139;140;148;146;135;139;136;216;144 07:38:49 INFO - PROCESS | 6278 | |12;stanford-crypto-pbkdf2;170;170;170;168;172;210;156;211;142;207 07:38:49 INFO - PROCESS | 6278 | |13;stanford-crypto-sha256-iterative;66;67;68;65;68;65;66;67;81;80 07:38:49 INFO - PROCESS | 6278 | __end_tp_report 07:38:49 INFO - PROCESS | 6278 | __start_cc_report 07:38:49 INFO - PROCESS | 6278 | _x_x_mozilla_cycle_collect,2298 07:38:49 INFO - PROCESS | 6278 | __end_cc_report 07:38:49 INFO - PROCESS | 6278 | __startTimestamp1462891129487__endTimestamp 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | ------- Summary: start ------- 07:38:49 INFO - PROCESS | 6278 | Number of tests: 14 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:2.00 (1.8%) stddev-sans-first:2.09 07:38:49 INFO - PROCESS | 6278 | Values: 111.0 107.0 114.0 112.0 112.0 113.0 112.0 114.0 113.0 112.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:143.50 stddev:26.30 (18.3%) stddev-sans-first:27.81 07:38:49 INFO - PROCESS | 6278 | Values: 148.0 118.0 144.0 199.0 123.0 140.0 143.0 118.0 171.0 118.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#2] audio-dft Cycles:10 Average:192.30 Median:186.00 stddev:17.38 (9.3%) stddev-sans-first:18.23 07:38:49 INFO - PROCESS | 6278 | Values: 185.0 187.0 185.0 186.0 184.0 186.0 185.0 183.0 203.0 239.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#3] audio-fft Cycles:10 Average:74.30 Median:58.50 stddev:25.72 (44.0%) stddev-sans-first:27.28 07:38:49 INFO - PROCESS | 6278 | Values: 75.0 75.0 57.0 126.0 58.0 64.0 56.0 59.0 116.0 57.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#4] audio-oscillator Cycles:10 Average:101.90 Median:83.00 stddev:30.16 (36.3%) stddev-sans-first:31.73 07:38:49 INFO - PROCESS | 6278 | Values: 91.0 95.0 183.0 109.0 95.0 77.0 102.0 81.0 85.0 101.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.50 stddev:1.37 (1.6%) stddev-sans-first:1.39 07:38:49 INFO - PROCESS | 6278 | Values: 88.0 86.0 88.0 85.0 87.0 85.0 89.0 87.0 88.0 86.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:94.00 stddev:16.45 (17.5%) stddev-sans-first:17.26 07:38:49 INFO - PROCESS | 6278 | Values: 94.0 94.0 94.0 94.0 107.0 146.0 94.0 97.0 93.0 94.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:1.34 (1.6%) stddev-sans-first:1.33 07:38:49 INFO - PROCESS | 6278 | Values: 84.0 87.0 85.0 87.0 84.0 87.0 85.0 86.0 84.0 84.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:58.50 stddev:19.51 (33.4%) stddev-sans-first:20.62 07:38:49 INFO - PROCESS | 6278 | Values: 60.0 61.0 59.0 57.0 58.0 58.0 59.0 120.0 57.0 57.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:70.00 stddev:5.04 (7.2%) stddev-sans-first:4.92 07:38:49 INFO - PROCESS | 6278 | Values: 65.0 65.0 82.0 76.0 70.0 69.0 70.0 70.0 69.0 70.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:1.27 (1.5%) stddev-sans-first:1.33 07:38:49 INFO - PROCESS | 6278 | Values: 84.0 82.0 85.0 84.0 85.0 86.0 83.0 85.0 85.0 86.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:142.00 stddev:24.33 (17.1%) stddev-sans-first:25.42 07:38:49 INFO - PROCESS | 6278 | Values: 136.0 139.0 140.0 148.0 146.0 135.0 139.0 136.0 216.0 144.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:171.00 stddev:23.71 (13.9%) stddev-sans-first:24.99 07:38:49 INFO - PROCESS | 6278 | Values: 170.0 170.0 170.0 168.0 172.0 210.0 156.0 211.0 142.0 207.0 07:38:49 INFO - PROCESS | 6278 | 07:38:49 INFO - PROCESS | 6278 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:67.50 stddev:6.00 (8.9%) stddev-sans-first:6.24 07:38:49 INFO - PROCESS | 6278 | Values: 66.0 67.0 68.0 65.0 68.0 65.0 66.0 67.0 81.0 80.0 07:38:49 INFO - PROCESS | 6278 | -------- Summary: end -------- 07:38:49 INFO - PROCESS | 6278 | 07:38:50 INFO - TEST-INFO | 6278: exit 0 07:38:50 INFO - TEST-OK | kraken | took 61146ms 07:38:50 INFO - SUITE-END | took 1177s 07:38:50 INFO - Completed test suite (00:19:37) 07:38:50 INFO - javascript benchmark 07:38:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.772306523521, 3429.725621950244, 3433.17603775094, 3437.364087643786, 3509.245512214873, 5626.184203290521, 5639.900219457208, 5680.687562184078, 5767.567432442568, 5772.365039845614, 5289.603279754015, 5447.774250793579, 5748.885022299552, 5839.357670656224, 5876.11858221266, 5140.820071297505, 5159.303494028296, 5229.60777941654, 5233.764480598382, 5326.6005049621235, 5301.416844147141, 5330.626856120073, 5339.092354299778, 5346.492083252104, 5397.676139431627, 5147.665401748893, 5183.688978661293, 5199.428062913076, 5253.369595648508, 5261.815836445725, 5152.6650767699975, 5164.845054648366, 5246.973765131188, 5247.895042099175, 5270.446603106695, 5129.717865517395, 5132.3327967364, 5157.149070403379, 5190.7404629768735, 5194.6883187008525, 5697.1739097831, 5698.572607054446, 5709.972205003098, 5726.484616384504, 5738.0532212184935, 6091.208142941434, 6135.815925522242, 6177.536684748662, 6230.345813689588, 6250.843728906768, 6269.811905642838, 6291.245050593912, 6312.147860038928, 6317.6209427434505, 6322.083297921821, 5849.327327357366, 5912.733926973297, 5915.645061296336, 5922.881542369171, 5945.7621695132575, 5668.688222147739, 6029.849253768691, 6189.938100618936, 6197.628142311476, 6273.529485288622, 6131.448169664752, 6190.0405437157315, 6208.689565521796, 6212.347703491158, 6215.875682486416, 5295.576353891679, 6272.843178920472, 6296.5592408531875, 6299.023651334051, 6310.684465776692, 5075.847724568269, 6122.184701511125, 6151.9234134026365, 6183.752649893954, 6201.224846894138, 6254.843628909222, 6299.811005669837, 6305.842353941188, 6310.242770867525, 6321.273053598803, 4938.4320803107385, 4943.876403089952, 4950.43070046942, 4963.329950456642, 4971.328870602422, 4352.69531132824, 4399.340098985178, 4453.421055262795, 4464.0, 4468.888277793081, 5800.477956983892, 5808.970955145282, 5822.650640961556, 5827.88344233113, 5846.678432686158, 5259.552938000313, 6267.874642507216, 6270.153529273581, 6284.685765711696, 6301.936980630227, 5572.5263352614675, 5649.943500564942, 5694.829155125353, 5709.65742055478, 5751.281089863767, 5419.403865574784, 5421.349438067457, 5428.9185662215095, 5439.592030597721, 5447.863803404867, 6394.104825324458, 6401.0078437841275, 6402.967985160044, 6403.231612206565, 6423.775167869289, 6157.0456579230295, 6169.2288463942, 6243.562950593415, 6253.749850005949, 6260.373962603885, 6327.620342779448, 6330.9050364245495, 6343.55595108338, 6343.682815859096, 6357.713902874474, 6154.661493617894, 6155.784547540815, 6178.62928224308, 6180.81457556274, 6192.256929168529, 6061.0, 6077.696115194134, 6159.784407545714, 6212.47193988516, 6249.937500624936, 6125.724342404514, 6155.6614386209185, 6158.137860699595, 6160.753569857335, 6167.383261673796, 5469.453054694499, 6114.960456722279, 6125.264968203844, 6148.815535533941, 6168.19813424252, 5833.212516310414, 5923.46688797993, 5927.8221653350465, 5972.790952316648, 5992.43071908168, 6134.079888016833, 6136.9873970794315, 6152.723127459365, 6158.938410615837, 6168.845778855385, 5909.556783241188, 5941.910871336847, 5969.3732158122775, 6019.759209631566, 6035.0], "value": 5702.6298881589, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.9139150648636, 493.6273113799079, 507.337924009168, 516.8294462827267, 524.339332441124, 30850.84574577127, 42927.78536107319, 43986.0, 44015.11969760603, 44176.337354939664, 44327.335089973596, 44685.0, 44790.0, 44793.32810007853, 44848.3272750909, 8415.621297041647, 9565.617375304997, 9765.804683906317, 9774.120329170373, 9779.804403911918, 8532.488050716976, 11443.713907152347, 11636.360000199986, 11684.0, 11791.823122653168, 11599.478023488964, 11653.766924661502, 11663.883361166407, 11671.299722016705, 11703.5903743369, 8484.830303393897, 11486.885131148749, 11550.71123221922, 11669.241499302603, 11694.883051169507, 11284.661460156209, 11416.771664566662, 11434.0, 11439.428028598537, 11447.885521144848, 40573.594264057276, 40872.795636021925, 40932.97667558305, 41042.76871693854, 41100.38349424761, 3232.0, 3280.2947366316357, 3335.466325387926, 3725.2549490102087, 3927.0967677434064, 28609.713902860913, 28895.104895104894, 29303.2674183145, 29349.26626834325, 29467.0, 2672.318558767521, 2716.375233696241, 2723.6459260296233, 2733.26201925479, 2792.706765789583, 469886.0, 474260.0, 476190.619046906, 476386.0, 476885.9999999965, 64426.6778666113, 64675.02987455192, 64950.0, 64983.67508162523, 65047.0, 37247.25505489875, 38444.0, 38473.42289865654, 39304.80347598244, 67937.0, 16783.58041048959, 16823.15884205785, 16872.325106995584, 16902.661946761244, 16905.408310709066, 12112.515499380102, 12301.56944506956, 13026.609201723963, 13146.276954767389, 13201.537946171838, 20250.493737656678, 20418.591628167353, 20462.181512739324, 20471.0, 20536.58926821485, 7200.927990720131, 7902.525848448997, 7934.127246002935, 7937.365010799238, 7961.1242763295995, 799.936005119589, 977.8924318324979, 1071.0628200324716, 1089.2593036734938, 1122.3041714137287, 29378.11865644034, 29580.852095739385, 30049.699503005126, 30156.54765178524, 30225.546616800766, 1080.2546243092397, 1112.9499172537255, 1160.1994623709613, 1219.71336735867, 1221.7477085986934, 15439.228038598025, 15441.382344706311, 15467.0, 15519.689606207812, 15877.285522151531, 663265.0, 669316.0, 672901.0, 673410.0, 675879.0, 51072.744636276584, 62524.0, 62538.374616253255, 62586.0, 62747.0, 1310.2073245686413, 1443.3288520837862, 1459.992700036493, 1493.6116609681344, 1497.9700405992037, 30358.69641303559, 30449.238769030064, 30551.388972220877, 31115.844420778656, 31167.532487013166, 52240.21639675484, 52590.68523286949, 52674.0, 52826.94346112979, 52951.94096118133, 408120.0, 479439.0, 481322.0, 485273.0, 488033.0, 22841.0, 24107.27678169658, 24942.62586061247, 25007.874645641354, 25026.0, 37565.0, 45991.3101303474, 46339.0, 46634.300485493426, 46912.7654361726, 37949.466948840956, 38088.0, 38297.425538616386, 38647.0, 39591.80204098961, 37320.0, 37635.62364376431, 37682.43476347912, 38481.807590961864, 38618.80690596529, 37639.81180094082, 37941.430878537394, 38435.80782096072, 38727.225455491294, 38759.806200968815, 30457.0, 31043.0, 31182.0, 31279.68720312859, 31313.53029704511, 24483.510329793662, 24519.26442206737, 24881.377965551004, 25105.0, 25163.344781215535], "value": 20122.537412534373, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.8639750900886, 390.8690588652801, 397.49121124960044, 410.97123201375916, 420.73493698969645, 19364.90317548412, 20693.482662933435, 21667.0, 21702.457438564023, 21835.344939651797, 20160.0, 21034.579308413824, 21297.893510532464, 21314.680279795815, 21502.247421340257, 5179.181525723841, 5321.36143662761, 5503.972480137613, 5506.091494903336, 5527.474889885474, 3865.5168103987003, 4059.8579049733266, 4060.736052156615, 4074.2666320062376, 5338.946610533903, 5677.17680936264, 6012.639241645494, 6014.8195554133445, 6034.5172386209, 6042.274927008765, 5924.674142922137, 6330.303666596671, 6520.608763474207, 6545.247296560861, 6551.541392102531, 3682.097886017916, 5435.891282174374, 5780.364159942403, 5790.305163380379, 5797.188393624896, 12425.378731063502, 13671.86328136716, 14061.859381406259, 14079.788803167961, 14098.0, 2090.080364639554, 2142.6143294207036, 2179.836512261587, 2180.4548862784304, 2291.0950174680906, 10143.391396516232, 10181.440020798853, 10365.740856478573, 12813.935930320382, 13642.386092625855, 1629.4215553478464, 1645.539249010279, 1666.4334126396961, 1728.2136627834304, 1796.6676164909534, 120068.39965800114, 126978.3651081748, 127131.99999999907, 128124.35937820344, 129102.3544882279, 9823.459709715891, 11582.305061696325, 11657.300561966309, 11718.414079296, 11722.355270460035, 15344.309506072253, 15409.075455472708, 15436.45972390961, 15456.613584660248, 15483.5354939352, 7275.454340924346, 8835.74688454453, 8893.066228045962, 8957.731268061969, 8998.235150012191, 8775.605097770616, 8817.206451419275, 8823.117688231125, 8858.149962674865, 8968.910310896938, 3633.6184700606586, 4266.87199384019, 4597.08058388317, 4630.189716799547, 4696.577308042224, 1571.2693597477228, 1725.5168552805235, 1773.7428072929354, 1900.9049547522568, 1967.9704804427945, 921.8571121476182, 948.2508818033814, 950.2825366847985, 975.6926568131016, 994.7264502261792, 19342.10526315795, 19580.216791328472, 20037.998100094937, 20947.37157885266, 21093.89453052725, 884.2844880930974, 895.6686026170229, 900.8243392538392, 932.7668082979255, 953.8378475659155, 10783.352998820095, 11168.553257869757, 11281.097512199005, 11300.660980170444, 11304.773904521864, 607534.0, 610052.0, 618837.0, 623371.0, 623831.0, 135147.32426337805, 135170.6482935158, 138238.0, 138815.0, 144356.0, 990.4601991914424, 996.4519514267124, 1017.0338178730236, 1018.7045756730465, 1020.2297265564451, 18566.350177743716, 18909.43271701851, 18990.43028709141, 19064.904675476533, 19089.904550477713, 50207.0, 52982.0, 54124.729376352865, 54464.0, 54994.72502637461, 80713.0, 81036.18963810286, 82066.58966705129, 82208.58895705722, 82553.58723206585, 8143.837123257384, 8243.0, 8309.916900830915, 8369.0, 8440.915590844259, 7153.964230178815, 9796.9020309796, 11842.171048026556, 11843.0, 12357.567485137977, 6376.489880809617, 9810.85283720759, 10594.205434592282, 10916.890831091587, 11490.827637585277, 10792.784144317227, 10994.505247264053, 11080.279781814157, 11098.667039988813, 11220.2145849793, 10367.963203679572, 11111.055560277468, 11190.32858028521, 11224.102071834397, 11405.543778248777, 8141.43009989295, 8204.54874981858, 8326.417150799629, 8417.747467575982, 8488.0, 8334.333253339597, 10526.052655261063, 10897.019268265894, 11266.774664506827, 11548.711282217675], "value": 9319.609179410096, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.8158139441884, 1600.11993403628, 1634.8773841961854, 1872.9719054214181, 1998.9800101998985, 7977.0826354969195, 8856.77858053549, 9096.954515227431, 9112.997570267273, 9236.815263694722, 8778.122187781235, 9042.276617870571, 9061.637534498612, 9062.320325975561, 9069.591868365922, 2155.8490905636613, 2241.540484200735, 2402.459446624513, 2414.927552173433, 2452.0559584559896, 2465.6794616699895, 2506.6616006839026, 2506.8997240110375, 2508.673872396586, 2512.849229046263, 2412.6984126984125, 2470.3083136721657, 2470.419451428913, 2476.702795664523, 2478.39279376553, 2121.533262682215, 2295.8392912496133, 2367.0886708617136, 2440.2313271319654, 2447.2535876557645, 2425.9150929717375, 2429.781319681237, 2466.9259922202364, 2467.7532246775177, 2468.938276543101, 9111.81776364469, 9191.35660503765, 9197.21823644991, 9218.170364667212, 9221.35450518464, 5455.372632147314, 5632.549396048306, 5637.943620563783, 5638.182463542805, 5639.887202255973, 3585.533880595532, 3595.6224596417524, 3640.290143422034, 3665.7433979621437, 3682.447632855093, 914.2092090341873, 1026.1329176845554, 1028.3213079367656, 1030.5311083457009, 1045.3309881675734, 12331.013518918466, 12359.949404300645, 12362.629121126382, 12391.256524608552, 12396.256224626552, 4435.112977404532, 4843.75781210938, 4855.198892182821, 4994.9750251248515, 4999.950000499948, 10322.638707645196, 10337.328073675188, 10338.483075846178, 10347.896521034843, 10352.327098738708, 5562.443755624486, 5599.216109744639, 5600.85997850057, 5626.043572592669, 5641.12562552805, 4494.348319493655, 4683.742394168354, 4918.827841025547, 5013.423456302499, 5018.824341148043, 4025.705849431667, 4060.8020633913065, 4126.566710495415, 4163.271427500175, 4163.83344666216, 1337.7859542473157, 1349.9605303916126, 1354.0, 1357.7216670582507, 1372.4715984346099, 832.6669332267021, 893.5264309915755, 922.9400088994194, 923.3997901364032, 970.8106919150839, 4889.828855990023, 5713.6857472838565, 5930.762769489172, 6045.0, 6059.6364218147055, 457.7802654725684, 463.54109431663085, 465.34851208307964, 972.2222222222194, 1028.4189432970347, 3214.7106760391675, 3993.0, 4026.315526360526, 4066.9796651016554, 4072.857449989295, 10878.945605272082, 11357.716057098474, 11520.308781473139, 11535.423228838525, 11535.884641153649, 9709.36889102206, 11207.887921120848, 11230.989210971053, 11266.324020558794, 11480.0, 8320.459170154041, 8537.658493660185, 8550.700725474579, 8557.657693692183, 8688.435251708745, 5818.301803783404, 6007.098935159761, 6103.877922441615, 6107.328193898578, 6117.694115294128, 10114.494275286059, 10127.392356458637, 10293.948530257301, 10308.484575771332, 10352.17182625373, 10002.44986525748, 10516.263861529926, 10573.207009474474, 10757.408342540923, 10835.349879007284, 7151.7854464366155, 7387.852242955219, 7404.148522919933, 7492.925070749223, 7678.1170165431695, 8578.485290882567, 8688.0, 8699.217070463688, 8765.78085547841, 8808.779780505538, 7548.396128309612, 7719.112302085332, 7730.226977302224, 7813.0, 7901.407394445325, 7502.137254215835, 7552.169261381354, 7575.431842611936, 7657.961710191414, 7813.14055453911, 7144.67848946809, 7241.891371629325, 7242.637868106679, 7254.455915806441, 7256.383207427428, 4054.8175332109536, 4886.511348865085, 4973.35346404965, 5004.699718016931, 5145.125328694206, 4941.0, 4997.550220480174, 5045.621578381562, 5103.489651034867, 5268.710220937885], "value": 4670.513630142374, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.63566448932306, 308.77922285565825, 319.1192309226534, 323.5809626533639, 327.50056164349365, 4820.349252850864, 5168.043911876305, 5441.56467482602, 5445.346558412991, 5500.312460942382, 4950.3069570260195, 5000.749363041413, 5086.92369614456, 5136.7928536793825, 5215.400228973669, 3554.3117019415763, 3634.67287944085, 3999.9600004000026, 4033.97983010086, 4091.4476545666403, 3579.74941754076, 3884.5726970033415, 4062.5531191568903, 4068.328725760261, 4081.693872959525, 3207.4386982278124, 3732.8055022392846, 3774.924501509968, 3832.3485007548643, 4051.858184963527, 3286.3920174767745, 3385.3737058644106, 3973.761574305522, 3974.2448934702456, 4031.5363733170766, 3373.3927892979254, 3518.7480012791834, 3521.9471707924404, 3531.434970404723, 3532.311199316134, 4454.866354009385, 4953.876153096201, 5043.470435604246, 5060.974695126538, 5157.149070403379, 4602.447706275235, 4602.677812553123, 4630.421197350332, 4643.512431194709, 4654.394928659286, 5012.020252000476, 5504.422035686275, 5542.7782888684405, 5546.639468434539, 5552.86117847053, 3464.4456886897974, 3491.7555771096036, 3649.981750091259, 3825.196708691178, 3910.022494376406, 5536.0, 5608.719564021823, 5841.620294680874, 5849.181114643953, 5888.322842873039, 3278.8032718036993, 3564.4118720410843, 3775.4903088082756, 3965.8017099145504, 4000.4799376081505, 3490.8254587271044, 3579.9105022374647, 4109.178164367078, 4109.835606575763, 4132.152908653718, 3867.9693161090654, 4357.411749413788, 4488.506264310923, 4611.262198048364, 4674.906501870011, 4902.8774280643265, 5148.433672296044, 5154.561862241751, 5544.889102217933, 5553.972230138824, 1130.1467392118898, 1213.50246398976, 1243.3410292544966, 1364.740699267136, 1380.7445622559858, 735.0260904301822, 774.666893235914, 859.4886042804521, 878.6748902905965, 897.8742975983367, 3782.678472329883, 4273.97863010683, 4289.919389096286, 4298.699091063596, 4301.0537681709975, 4184.958150418518, 4408.561322168714, 4507.323901414814, 4718.716876987323, 4897.314375987365, 4150.439690641725, 4375.7374557526655, 4665.486796452387, 5136.58743845438, 5195.986782577361, 2800.81794683349, 2952.2619345163707, 2957.7190166934106, 2980.64232292122, 2989.312458134641, 4825.734584597811, 5936.228290322317, 5968.7015649217365, 5984.862876053536, 5995.871465629726, 4395.934060989088, 4828.527299173786, 5647.604667673223, 5947.791827286024, 5967.642781101527, 5025.899482010339, 5411.2965314508865, 6025.8192254232445, 6038.788642397583, 6044.667543285162, 4755.904881902412, 4949.292494089534, 5785.334686511105, 5786.3056433228285, 5832.62087964281, 4735.928961065518, 4928.482509336468, 5233.136532472252, 5638.971805140948, 5703.08750599902, 5314.760835762478, 6016.338202797601, 6016.42843929826, 6087.417271509528, 6117.96941015292, 3736.8878933632036, 3879.8836034919, 3885.68914486846, 3887.9416808747324, 3890.513685789276, 5670.432956704297, 5685.829425117253, 5724.942750572441, 5785.859232084156, 5811.651300921958, 4776.349126234973, 5245.659032162897, 5608.775648973995, 5655.915161272666, 5703.543716502587, 5246.947530524646, 5583.385827559046, 5625.212470254086, 5636.943630563642, 5650.63270887391, 4570.88572785683, 5099.69401835891, 5303.655262408085, 5459.208414779914, 5528.91706624409, 4806.4953179917, 5295.655782374133, 5471.467988963097, 5516.172574113915, 5532.944670553404, 5194.818181363634, 5482.835514934558, 5486.119787111798, 5486.506214440719, 5503.422140675171], "value": 3992.9095724535073, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.7486793244939, 727.923568025357, 727.9963600181999, 729.6534146280518, 742.5878637356265, 14688.339024743886, 15244.161571113584, 16255.837441625596, 16824.24290906909, 16828.915855420735, 16012.119333436656, 16094.436694715687, 16178.919105404457, 16180.0, 16196.83803161968, 726.1032624708481, 748.4648476339416, 926.5321012888498, 936.9718908432724, 940.5109343141562, 903.7559858838109, 935.443411170356, 935.705252845355, 939.685205456173, 946.6781294359917, 972.1348000279756, 974.6101559376235, 982.8378317577591, 984.3011461862086, 986.5659109991617, 752.0336367767393, 775.7711475114854, 776.6582703610393, 981.0875885426515, 981.8821741391043, 575.5021906051278, 699.1400577289962, 720.6720941971442, 723.4646361692362, 724.5978481942519, 14815.70368592622, 14866.925665371713, 14881.70236595273, 14895.180765057918, 14928.477503287333, 11944.940275298568, 12009.399530023551, 12177.269363838199, 12254.0, 12341.0, 10811.0, 10960.616378426717, 11036.0, 11438.0, 11608.0, 3980.5223373195113, 3993.5008123984503, 4005.499312585927, 4121.29937910556, 4139.9172016559805, 59549.0, 81920.59039704823, 82198.0, 82542.17457825405, 82576.76134857982, 9060.957989831253, 9189.218916392048, 9261.72214833556, 9510.334276600572, 9519.476428796344, 20377.592448150954, 20538.486537836376, 20565.58868822615, 20583.4854128645, 20591.176352945713, 7223.674934627849, 7237.203907570163, 7287.927120728831, 7289.343959043712, 7371.226021267689, 3917.941230881539, 3947.4276229946495, 3976.6619837313574, 4039.7172197946447, 4058.0, 3319.170207448138, 3400.7449441292, 3417.8461969211444, 3421.4354631485526, 3426.554547908756, 634.80321100459, 635.9841003974938, 646.1019183335168, 651.837040739815, 660.9239937407164, 475.1020571120585, 577.3995045153077, 605.5458406195354, 612.4640939178219, 622.539320902538, 7932.167122452175, 8217.876731849026, 8271.503709777433, 8384.958075209584, 8407.41148119638, 539.8623351045459, 543.1933578635723, 590.439082871274, 609.7682880505469, 610.3377835048933, 3689.1883785567134, 3767.56672982605, 3914.784686842251, 3970.9602903971168, 3988.3219852625125, 77256.0, 78059.0, 84414.0, 90962.54518727364, 93170.0, 71968.64015679888, 72576.91134632984, 77774.0, 81136.59431702804, 82699.0, 61299.38700613026, 62986.74026519444, 63759.36240637627, 63980.20148488882, 64266.0, 7197.856042879009, 7524.924750752422, 7642.7325043623205, 7644.617769111411, 7730.0, 41298.58701413067, 41304.38043429411, 41855.7907210462, 42082.7895860531, 42101.36847947222, 56797.43202567921, 58001.41998580129, 58007.12989305248, 59600.58393000778, 60291.69854150877, 10225.642102526375, 10295.639652612123, 10716.35701857891, 11196.888031119584, 11352.205345625727, 12065.939670301943, 12092.818607721067, 12308.446119924803, 12489.93755031219, 12597.622071337875, 8440.258686404064, 11343.149263805082, 11667.241629294409, 12197.573084941985, 12431.813522797345, 10565.0, 11258.38078905668, 11911.76176476483, 11914.344711040976, 11998.940005299917, 11145.554177832795, 11287.379194144401, 11359.886401135884, 11774.234674746114, 11798.997085247851, 9355.672551460666, 9461.290403219647, 9496.905030949601, 9506.90493095088, 9778.55996480146, 7591.127020392725, 8026.277635012877, 9379.531023448937, 9718.173955213886, 9738.61045558198], "value": 6788.539524292474, "unit": "score"}], "name": "dromaeo_css", "value": 7165.922167544599, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 107.0, 114.0, 112.0, 112.0, 113.0, 112.0, 114.0, 113.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 144.0, 199.0, 123.0, 140.0, 143.0, 118.0, 171.0, 118.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 185.0, 186.0, 184.0, 186.0, 185.0, 183.0, 203.0, 239.0], "value": 192.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 57.0, 126.0, 58.0, 64.0, 56.0, 59.0, 116.0, 57.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 183.0, 109.0, 95.0, 77.0, 102.0, 81.0, 85.0, 101.0], "value": 101.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 85.0, 87.0, 85.0, 89.0, 87.0, 88.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 107.0, 146.0, 94.0, 97.0, 93.0, 94.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 85.0, 87.0, 84.0, 87.0, 85.0, 86.0, 84.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 57.0, 58.0, 58.0, 59.0, 120.0, 57.0, 57.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 82.0, 76.0, 70.0, 69.0, 70.0, 70.0, 69.0, 70.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 85.0, 84.0, 85.0, 86.0, 83.0, 85.0, 85.0, 86.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 139.0, 140.0, 148.0, 146.0, 135.0, 139.0, 136.0, 216.0, 144.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 170.0, 168.0, 172.0, 210.0, 156.0, 211.0, 142.0, 207.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 65.0, 68.0, 65.0, 66.0, 67.0, 81.0, 80.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1510.1, "alertThreshold": 2.0}]} 07:38:50 INFO - Return code: 0 07:38:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:38:50 ERROR - # TBPL SUCCESS # 07:38:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:38:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:38:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:38:50 INFO - 2016-05-10 07:38:50.773 screenresolution[6282:104781] starting screenresolution argv=screenresolution get 07:38:50 INFO - 2016-05-10 07:38:50.776 screenresolution[6282:104781] Display 0: 1600x1200x32@60 07:38:50 INFO - 2016-05-10 07:38:50.783 screenresolution[6283:104784] starting screenresolution argv=screenresolution list 07:38:50 INFO - Available Modes on Display 0 07:38:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:38:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:38:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:38:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:38:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:38:51 INFO - Intel Iris: 07:38:51 INFO - Chipset Model: Intel Iris 07:38:51 INFO - Type: GPU 07:38:51 INFO - Bus: Built-In 07:38:51 INFO - VRAM (Dynamic, Max): 1536 MB 07:38:51 INFO - Vendor: Intel (0x8086) 07:38:51 INFO - Device ID: 0x0a2e 07:38:51 INFO - Revision ID: 0x0009 07:38:51 INFO - Displays: 07:38:51 INFO - Display: 07:38:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:38:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:38:51 INFO - Display Serial Number: 1600x1200 60 07:38:51 INFO - Main Display: Yes 07:38:51 INFO - Mirror: Off 07:38:51 INFO - Online: Yes 07:38:51 INFO - Rotation: Supported 07:38:51 INFO - Return code: 0 07:38:51 INFO - Running post-action listener: _resource_record_post_action 07:38:51 INFO - ##### 07:38:51 INFO - ##### Finished run-tests step (success) 07:38:51 INFO - ##### 07:38:51 INFO - Running post-run listener: _resource_record_post_run 07:38:52 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1547776; Write bytes: 694448128; Read time: 24; Write time: 1867 07:38:52 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 379562496; Write bytes: 373888512; Read time: 28366; Write time: 732 07:38:52 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1547776; Write bytes: 297814528; Read time: 24; Write time: 1086 07:38:52 INFO - Running post-run listener: _upload_blobber_files 07:38:52 INFO - Blob upload gear active. 07:38:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:38:52 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:38:52 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:38:52 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:38:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:38:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:53 INFO - (blobuploader) - INFO - Done attempting. 07:38:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:38:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:38:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:54 INFO - (blobuploader) - INFO - Done attempting. 07:38:54 INFO - (blobuploader) - INFO - Iteration through files over. 07:38:54 INFO - Return code: 0 07:38:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:38:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:38:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eab8c7e4b65430f033c6cbe9ad4ebcf0de3121a56c988e092dfd64f51668db90de4b1ceb614cfea6915cf902c371c571d03277ccfc01028a2391f9f9568b5194", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b561fbae00e2a7624cf7c45520ce3403bff5df3e7d9e54d7de70854eb57ca6d12feee98a6c7384ea3d8eafeb839907d109b0b9539d6cede6d8af8df6a2216e4f"} 07:38:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:38:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:38:54 INFO - Contents: 07:38:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eab8c7e4b65430f033c6cbe9ad4ebcf0de3121a56c988e092dfd64f51668db90de4b1ceb614cfea6915cf902c371c571d03277ccfc01028a2391f9f9568b5194", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b561fbae00e2a7624cf7c45520ce3403bff5df3e7d9e54d7de70854eb57ca6d12feee98a6c7384ea3d8eafeb839907d109b0b9539d6cede6d8af8df6a2216e4f"} 07:38:54 INFO - Running post-run listener: copy_logs_to_upload_dir 07:38:54 INFO - Copying logs to upload dir... 07:38:54 INFO - mkdir: /builds/slave/test/build/upload/logs 07:38:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.676628 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-10 07:38:54.244760) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 07:38:54.248445) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yFxmtyRx4v/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eab8c7e4b65430f033c6cbe9ad4ebcf0de3121a56c988e092dfd64f51668db90de4b1ceb614cfea6915cf902c371c571d03277ccfc01028a2391f9f9568b5194", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b561fbae00e2a7624cf7c45520ce3403bff5df3e7d9e54d7de70854eb57ca6d12feee98a6c7384ea3d8eafeb839907d109b0b9539d6cede6d8af8df6a2216e4f"} build_url:https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011204 build_url: 'https://queue.taskcluster.net/v1/task/JhN5rLh4StaI7K0BismYQg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eab8c7e4b65430f033c6cbe9ad4ebcf0de3121a56c988e092dfd64f51668db90de4b1ceb614cfea6915cf902c371c571d03277ccfc01028a2391f9f9568b5194", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b561fbae00e2a7624cf7c45520ce3403bff5df3e7d9e54d7de70854eb57ca6d12feee98a6c7384ea3d8eafeb839907d109b0b9539d6cede6d8af8df6a2216e4f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 07:38:54.275932) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:38:54.276773) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yFxmtyRx4v/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.r6d7TcxdEN/Listeners TMPDIR=/var/folders/tq/gxc_mw6n779fd3_fqt4jn6lc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005422 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 07:38:54.324805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 07:38:54.325125) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 07:38:54.325420) ========= ========= Total master_lag: 0.29 =========