builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0131 starttime: 1463176410.18 results: success (0) buildid: 20160513135736 builduid: 39ad5e3a832143f19cbfc5e9ce344704 revision: edcd8d596806c757423acc2573c9047c569fb4f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:30.176101) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:30.176639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:30.176962) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:30.228091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:30.228544) ========= 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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-13 14:53:30-- 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% 7.97M=0.001s 2016-05-13 14:53:30 (7.97 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154099 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:30.412250) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:30.412585) ========= 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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.020077 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:30.496592) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 14:53:30.496945) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev edcd8d596806c757423acc2573c9047c569fb4f8 --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 edcd8d596806c757423acc2573c9047c569fb4f8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eIj4RUTPwO/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.wh9bE5rBcr/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-13 14:53:30,578 truncating revision to first 12 chars 2016-05-13 14:53:30,578 Setting DEBUG logging. 2016-05-13 14:53:30,578 attempt 1/10 2016-05-13 14:53:30,578 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/edcd8d596806?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 14:53:32,324 unpacking tar archive at: fx-team-edcd8d596806/testing/mozharness/ program finished with exit code 0 elapsedTime=1.991543 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 14:53:32.510346) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:32.510713) ========= script_repo_revision: edcd8d596806c757423acc2573c9047c569fb4f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:32.511151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:32.511474) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 14:53:32.525788) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-05-13 14:53:32.526131) ========= /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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:53:32 INFO - MultiFileLogger online at 20160513 14:53:32 in /builds/slave/test 14:53:32 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 14:53:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:53:32 INFO - {'append_to_log': False, 14:53:32 INFO - 'base_work_dir': '/builds/slave/test', 14:53:32 INFO - 'blob_upload_branch': 'Fx-Team', 14:53:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:53:32 INFO - 'branch': 'Fx-Team', 14:53:32 INFO - 'buildbot_json_path': 'buildprops.json', 14:53:32 INFO - 'config_files': ('talos/mac_config.py',), 14:53:32 INFO - 'default_actions': ('clobber', 14:53:32 INFO - 'read-buildbot-config', 14:53:32 INFO - 'download-and-extract', 14:53:32 INFO - 'populate-webroot', 14:53:32 INFO - 'create-virtualenv', 14:53:32 INFO - 'install', 14:53:32 INFO - 'run-tests'), 14:53:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:53:32 INFO - 'download_minidump_stackwalk': True, 14:53:32 INFO - 'download_symbols': 'ondemand', 14:53:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:53:32 INFO - 'tooltool.py': '/tools/tooltool.py', 14:53:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:53:32 INFO - '/tools/misc-python/virtualenv.py')}, 14:53:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:53:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:53:32 INFO - 'installer_path': 'installer.exe', 14:53:32 INFO - 'log_level': 'info', 14:53:32 INFO - 'log_name': 'talos', 14:53:32 INFO - 'log_to_console': True, 14:53:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:53:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:53:32 INFO - 'opt_config_files': (), 14:53:32 INFO - 'pip_index': False, 14:53:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:53:32 INFO - 'cmd': ('bash', 14:53:32 INFO - '-c', 14:53:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:53:32 INFO - 'enabled': True, 14:53:32 INFO - 'halt_on_failure': False, 14:53:32 INFO - 'name': 'check_screen_resolution'},), 14:53:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:53:32 INFO - 'cmd': ('bash', 14:53:32 INFO - '-c', 14:53:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:53:32 INFO - 'enabled': True, 14:53:32 INFO - 'halt_on_failure': False, 14:53:32 INFO - 'name': 'check_screen_resolution'},), 14:53:32 INFO - 'run_cmd_checks_enabled': True, 14:53:32 INFO - 'sps_profile': False, 14:53:32 INFO - 'sps_profile_interval': 0, 14:53:32 INFO - 'suite': 'dromaeojs', 14:53:32 INFO - 'system_bits': '32', 14:53:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:53:32 INFO - 'title': 't-yosemite-r7-0131', 14:53:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:53:32 INFO - 'use_talos_json': True, 14:53:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:53:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:53:32 INFO - 'work_dir': 'build'} 14:53:32 INFO - ##### 14:53:32 INFO - ##### Running clobber step. 14:53:32 INFO - ##### 14:53:32 INFO - Running pre-action listener: _resource_record_pre_action 14:53:32 INFO - Running main action method: clobber 14:53:32 INFO - rmtree: /builds/slave/test/build 14:53:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:53:34 INFO - Running post-action listener: _resource_record_post_action 14:53:34 INFO - ##### 14:53:34 INFO - ##### Finished clobber step (success) 14:53:34 INFO - ##### 14:53:34 INFO - ##### 14:53:34 INFO - ##### Running read-buildbot-config step. 14:53:34 INFO - ##### 14:53:34 INFO - Running pre-action listener: _resource_record_pre_action 14:53:34 INFO - Running main action method: read_buildbot_config 14:53:34 INFO - Using buildbot properties: 14:53:34 INFO - { 14:53:34 INFO - "project": "", 14:53:34 INFO - "product": "firefox", 14:53:34 INFO - "script_repo_revision": "production", 14:53:34 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:53:34 INFO - "repository": "", 14:53:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:53:34 INFO - "buildid": "20160513135736", 14:53:34 INFO - "pgo_build": "False", 14:53:34 INFO - "basedir": "/builds/slave/test", 14:53:34 INFO - "buildnumber": 772, 14:53:34 INFO - "slavename": "t-yosemite-r7-0131", 14:53:34 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:53:34 INFO - "platform": "yosemite_r7", 14:53:34 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:53:34 INFO - "branch": "fx-team", 14:53:34 INFO - "repo_path": "integration/fx-team", 14:53:34 INFO - "revision": "edcd8d596806c757423acc2573c9047c569fb4f8", 14:53:34 INFO - "stage_platform": "macosx64", 14:53:34 INFO - "builduid": "39ad5e3a832143f19cbfc5e9ce344704", 14:53:34 INFO - "slavebuilddir": "test" 14:53:34 INFO - } 14:53:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:53:34 INFO - Running post-action listener: _resource_record_post_action 14:53:34 INFO - ##### 14:53:34 INFO - ##### Finished read-buildbot-config step (success) 14:53:34 INFO - ##### 14:53:34 INFO - ##### 14:53:34 INFO - ##### Running download-and-extract step. 14:53:34 INFO - ##### 14:53:34 INFO - Running pre-action listener: _resource_record_pre_action 14:53:34 INFO - Running main action method: download_and_extract 14:53:34 INFO - mkdir: /builds/slave/test/build/tests 14:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:34 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:53:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:53:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:53:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 14:53:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 14:53:40 INFO - Downloaded 1199 bytes. 14:53:40 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:53:40 INFO - Using the following test package requirements: 14:53:40 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:53:40 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:53:40 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:53:40 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'jsshell-mac.zip'], 14:53:40 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:53:40 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:53:40 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:53:40 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:53:40 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:53:40 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:53:40 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:53:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:40 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:53:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:53:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:53:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 14:53:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 14:53:46 INFO - Downloaded 18153816 bytes. 14:53:46 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'] 14:53:46 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 14:53:49 INFO - Return code: 0 14:53:49 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 14:53: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')]} 14:53:49 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:53:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:53:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:53:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 14:53:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 14:53:53 INFO - Downloaded 18153816 bytes. 14:53:53 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'] 14:53:53 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 14:53:57 INFO - Return code: 0 14:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:57 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:53:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:53:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:53:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 14:53:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 14:53:58 INFO - Downloaded 10840239 bytes. 14:53:58 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'] 14:53:58 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 14:53:58 INFO - Return code: 0 14:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:58 INFO - https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:53:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:53:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:53:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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 14:54:01 INFO - Downloaded 110063875 bytes. 14:54:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:54:01 INFO - mkdir: /builds/slave/test/properties 14:54:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:54:01 INFO - Writing to file /builds/slave/test/properties/build_url 14:54:01 INFO - Contents: 14:54:01 INFO - build_url:https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:54:02 INFO - Running post-action listener: _resource_record_post_action 14:54:02 INFO - Running post-action listener: set_extra_try_arguments 14:54:02 INFO - ##### 14:54:02 INFO - ##### Finished download-and-extract step (success) 14:54:02 INFO - ##### 14:54:02 INFO - ##### 14:54:02 INFO - ##### Running populate-webroot step. 14:54:02 INFO - ##### 14:54:02 INFO - Running pre-action listener: _resource_record_pre_action 14:54:02 INFO - Running main action method: populate_webroot 14:54:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:54:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:54:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:54:02 INFO - '--tppagecycles', 14:54:02 INFO - '7'], 14:54:02 INFO - 'tests': ['tsvgm']}}, 14:54:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:54:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:54:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:54:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:54:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:02 INFO - 'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:54:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:54:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:02 INFO - 'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['damp', 'tps']}, 14:54:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:02 INFO - 'tests': ['damp', 'tps']}, 14:54:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['dromaeo_dom']}, 14:54:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:54:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['a11yr', 14:54:02 INFO - 'ts_paint', 14:54:02 INFO - 'tpaint', 14:54:02 INFO - 'sessionrestore', 14:54:02 INFO - 'sessionrestore_no_auto_restore', 14:54:02 INFO - 'tabpaint']}, 14:54:02 INFO - 'other-e10s': {'tests': ['a11yr', 14:54:02 INFO - 'ts_paint', 14:54:02 INFO - 'tpaint', 14:54:02 INFO - 'sessionrestore', 14:54:02 INFO - 'sessionrestore_no_auto_restore', 14:54:02 INFO - 'tabpaint']}, 14:54:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:54:02 INFO - 'ts_paint', 14:54:02 INFO - 'tpaint', 14:54:02 INFO - 'sessionrestore', 14:54:02 INFO - 'sessionrestore_no_auto_restore', 14:54:02 INFO - 'tabpaint']}, 14:54:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:54:02 INFO - 'ts_paint', 14:54:02 INFO - 'tpaint', 14:54:02 INFO - 'sessionrestore', 14:54:02 INFO - 'sessionrestore_no_auto_restore', 14:54:02 INFO - 'tabpaint']}, 14:54:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['a11yr', 14:54:02 INFO - 'ts_paint', 14:54:02 INFO - 'tpaint', 14:54:02 INFO - 'sessionrestore', 14:54:02 INFO - 'sessionrestore_no_auto_restore', 14:54:02 INFO - 'tabpaint']}, 14:54:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['a11yr', 14:54:02 INFO - 'ts_paint', 14:54:02 INFO - 'tpaint', 14:54:02 INFO - 'sessionrestore', 14:54:02 INFO - 'sessionrestore_no_auto_restore', 14:54:02 INFO - 'tabpaint']}, 14:54:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['tsvgx', 14:54:02 INFO - 'tsvgr_opacity', 14:54:02 INFO - 'tart', 14:54:02 INFO - 'tscrollx', 14:54:02 INFO - 'cart']}, 14:54:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:54:02 INFO - 'tsvgr_opacity', 14:54:02 INFO - 'tart', 14:54:02 INFO - 'tscrollx', 14:54:02 INFO - 'cart']}, 14:54:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:02 INFO - 'talos_options': ['--disable-e10s'], 14:54:02 INFO - 'tests': ['tp5o']}, 14:54:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:02 INFO - 'tests': ['tp5o']}, 14:54:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:02 INFO - 'talos_options': ['--disable-e10s', 14:54:02 INFO - '--xperf_path', 14:54:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:54:02 INFO - 'tests': ['tp5n']}, 14:54:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:54:02 INFO - 'talos_options': ['--xperf_path', 14:54:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:54:02 INFO - 'tests': ['tp5n']}}, 14:54:02 INFO - 'talos.zip': {'path': '', 14:54:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:54:02 INFO - Running post-action listener: _resource_record_post_action 14:54:02 INFO - ##### 14:54:02 INFO - ##### Finished populate-webroot step (success) 14:54:02 INFO - ##### 14:54:02 INFO - ##### 14:54:02 INFO - ##### Running create-virtualenv step. 14:54:02 INFO - ##### 14:54:02 INFO - Running pre-action listener: _resource_record_pre_action 14:54:02 INFO - Running main action method: create_virtualenv 14:54:02 INFO - Creating virtualenv /builds/slave/test/build/venv 14:54:02 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 14:54:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:54:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:54:02 INFO - Using real prefix '/tools/python27' 14:54:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:54:03 INFO - Installing distribute.............................................................................................................................................................................................done. 14:54:06 INFO - Installing pip.................done. 14:54:06 INFO - Return code: 0 14:54:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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 0x10d5bea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe641c9e260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, '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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:54: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 14:54: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 14:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:06 INFO - 'HOME': '/Users/cltbld', 14:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:06 INFO - 'LOGNAME': 'cltbld', 14:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:06 INFO - 'MOZ_NO_REMOTE': '1', 14:54:06 INFO - 'NO_EM_RESTART': '1', 14:54:06 INFO - 'PAGER': '/bin/cat', 14:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:06 INFO - 'PWD': '/builds/slave/test', 14:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:06 INFO - 'SHELL': '/bin/bash', 14:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:54:06 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:06 INFO - 'USER': 'cltbld', 14:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:06 INFO - 'XPC_FLAGS': '0x0', 14:54:06 INFO - 'XPC_SERVICE_NAME': '0', 14:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:06 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 14:54:06 INFO - Cleaning up... 14:54:06 INFO - Return code: 0 14:54:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe641c9e260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, '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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:54: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', 'psutil>=3.1.1'] in /builds/slave/test/build 14:54: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 psutil>=3.1.1 14:54:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:54:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:06 INFO - 'HOME': '/Users/cltbld', 14:54:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:06 INFO - 'LOGNAME': 'cltbld', 14:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:06 INFO - 'MOZ_NO_REMOTE': '1', 14:54:06 INFO - 'NO_EM_RESTART': '1', 14:54:06 INFO - 'PAGER': '/bin/cat', 14:54:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:06 INFO - 'PWD': '/builds/slave/test', 14:54:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:06 INFO - 'SHELL': '/bin/bash', 14:54:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:54:06 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:54:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:06 INFO - 'USER': 'cltbld', 14:54:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:06 INFO - 'XPC_FLAGS': '0x0', 14:54:06 INFO - 'XPC_SERVICE_NAME': '0', 14:54:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:06 INFO - Downloading/unpacking psutil>=3.1.1 14:54:06 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 14:54:06 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 14:54:06 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 14:54:06 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 14:54: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 14:54: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 14:54:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:54:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:54:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:54:09 INFO - Installing collected packages: psutil 14:54:09 INFO - Running setup.py install for psutil 14:54:09 INFO - building 'psutil._psutil_osx' extension 14:54:09 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 14:54: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 14:54: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 14:54: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 14:54:10 INFO - building 'psutil._psutil_posix' extension 14:54: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 14:54:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:54:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:54:10 INFO - ^ 14:54:10 INFO - 1 warning generated. 14:54:10 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 14:54:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:54:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:54:10 INFO - Successfully installed psutil 14:54:10 INFO - Cleaning up... 14:54:10 INFO - Return code: 0 14:54:10 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 14:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:10 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.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5bea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe641c9e260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, '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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:54:10 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.1'] in /builds/slave/test/build 14:54:10 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.1 14:54:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:54:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:10 INFO - 'HOME': '/Users/cltbld', 14:54:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:10 INFO - 'LOGNAME': 'cltbld', 14:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:10 INFO - 'MOZ_NO_REMOTE': '1', 14:54:10 INFO - 'NO_EM_RESTART': '1', 14:54:10 INFO - 'PAGER': '/bin/cat', 14:54:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:10 INFO - 'PWD': '/builds/slave/test', 14:54:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:10 INFO - 'SHELL': '/bin/bash', 14:54:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:54:10 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:54:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:10 INFO - 'USER': 'cltbld', 14:54:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:10 INFO - 'XPC_FLAGS': '0x0', 14:54:10 INFO - 'XPC_SERVICE_NAME': '0', 14:54:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:10 INFO - Downloading/unpacking mozsystemmonitor==0.1 14:54:10 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 14:54:10 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 14:54:10 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 14:54:10 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 14:54:10 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 14:54:10 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 14:54:13 INFO - Downloading mozsystemmonitor-0.1.tar.gz 14:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 14:54:13 INFO - Installing collected packages: mozsystemmonitor 14:54:13 INFO - Running setup.py install for mozsystemmonitor 14:54:13 INFO - Successfully installed mozsystemmonitor 14:54:13 INFO - Cleaning up... 14:54:13 INFO - Return code: 0 14:54:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d5bea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe641c9e260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, '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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:54:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:54:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:54:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:13 INFO - 'HOME': '/Users/cltbld', 14:54:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:13 INFO - 'LOGNAME': 'cltbld', 14:54:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:13 INFO - 'MOZ_NO_REMOTE': '1', 14:54:13 INFO - 'NO_EM_RESTART': '1', 14:54:13 INFO - 'PAGER': '/bin/cat', 14:54:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:13 INFO - 'PWD': '/builds/slave/test', 14:54:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:13 INFO - 'SHELL': '/bin/bash', 14:54:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:54:13 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:54:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:13 INFO - 'USER': 'cltbld', 14:54:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:13 INFO - 'XPC_FLAGS': '0x0', 14:54:13 INFO - 'XPC_SERVICE_NAME': '0', 14:54:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:13 INFO - Downloading/unpacking blobuploader==1.2.4 14:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:16 INFO - Downloading blobuploader-1.2.4.tar.gz 14:54:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:54:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:54:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:17 INFO - Downloading docopt-0.6.1.tar.gz 14:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:54:17 INFO - Installing collected packages: blobuploader, requests, docopt 14:54:17 INFO - Running setup.py install for blobuploader 14:54:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:54:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:54:17 INFO - Running setup.py install for requests 14:54:18 INFO - Running setup.py install for docopt 14:54:18 INFO - Successfully installed blobuploader requests docopt 14:54:18 INFO - Cleaning up... 14:54:18 INFO - Return code: 0 14:54:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:18 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 0x10d5bea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe641c9e260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, '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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:54:18 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 14:54:18 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 14:54:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:54:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:18 INFO - 'HOME': '/Users/cltbld', 14:54:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:18 INFO - 'LOGNAME': 'cltbld', 14:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:18 INFO - 'MOZ_NO_REMOTE': '1', 14:54:18 INFO - 'NO_EM_RESTART': '1', 14:54:18 INFO - 'PAGER': '/bin/cat', 14:54:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:18 INFO - 'PWD': '/builds/slave/test', 14:54:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:18 INFO - 'SHELL': '/bin/bash', 14:54:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:54:18 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:54:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:18 INFO - 'USER': 'cltbld', 14:54:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:18 INFO - 'XPC_FLAGS': '0x0', 14:54:18 INFO - 'XPC_SERVICE_NAME': '0', 14:54:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:18 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Jll_26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:18 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-53fvdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:18 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-P9ubIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:18 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-xIGCVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:18 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ou7ZAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-SVOUPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-CmW8sB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-PopQ7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-i1OWQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-LiOHvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-vnDvjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-NV6Bdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-fXcAVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:19 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-6AEuo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-kd12sO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-DLKZ_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:20 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-si8VDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:54:20 INFO - Running setup.py install for manifestparser 14:54:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:54:20 INFO - Running setup.py install for mozcrash 14:54:20 INFO - Running setup.py install for mozdebug 14:54:20 INFO - Running setup.py install for mozdevice 14:54:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:54:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:54:20 INFO - Running setup.py install for mozfile 14:54:21 INFO - Running setup.py install for mozhttpd 14:54:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:54:21 INFO - Running setup.py install for mozinfo 14:54:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:54:21 INFO - Running setup.py install for mozInstall 14:54:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:54:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:54:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:54:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:54:21 INFO - Running setup.py install for mozleak 14:54:21 INFO - Running setup.py install for mozlog 14:54:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:54:21 INFO - Running setup.py install for moznetwork 14:54:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:54:21 INFO - Running setup.py install for mozprocess 14:54:22 INFO - Running setup.py install for mozprofile 14:54:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:54:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:54:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:54:22 INFO - Running setup.py install for mozrunner 14:54:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:54:22 INFO - Running setup.py install for mozscreenshot 14:54:22 INFO - Running setup.py install for moztest 14:54:22 INFO - Running setup.py install for mozversion 14:54:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:54:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:54:22 INFO - Cleaning up... 14:54:22 INFO - Return code: 0 14:54:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:22 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 0x10d5bea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe641c9e260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, '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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:54:22 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 14:54:22 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 14:54:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:54:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:22 INFO - 'HOME': '/Users/cltbld', 14:54:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:22 INFO - 'LOGNAME': 'cltbld', 14:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:22 INFO - 'MOZ_NO_REMOTE': '1', 14:54:22 INFO - 'NO_EM_RESTART': '1', 14:54:22 INFO - 'PAGER': '/bin/cat', 14:54:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:22 INFO - 'PWD': '/builds/slave/test', 14:54:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:22 INFO - 'SHELL': '/bin/bash', 14:54:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:54:22 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:54:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:22 INFO - 'USER': 'cltbld', 14:54:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:22 INFO - 'XPC_FLAGS': '0x0', 14:54:22 INFO - 'XPC_SERVICE_NAME': '0', 14:54:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:23 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-FHE4pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:23 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)) 14:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:23 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-aqt9ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:23 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)) 14:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:23 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-OCxt70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:23 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)) 14:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:23 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-V26JC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:23 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)) 14:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:23 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-QEdUvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:23 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)) 14:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:23 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-sOph0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:23 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)) 14:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:23 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-7J9maS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:23 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)) 14:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:23 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-iiA5Rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:23 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)) 14:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:23 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-SrVVmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:24 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)) 14:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:24 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ycLqwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 14:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:24 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-fTVoP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:24 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)) 14:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:24 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ysuTrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:24 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)) 14:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:24 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-zBovif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:24 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)) 14:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:24 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-G9dWzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:24 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)) 14:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:24 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-gfmSMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:24 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)) 14:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:24 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-KMPkLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:24 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)) 14:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:24 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-TIaOCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:24 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)) 14:54:24 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)) 14:54:24 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)) 14:54:24 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)) 14:54:24 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)) 14:54:24 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)) 14:54:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:27 INFO - Downloading blessings-1.6.tar.gz 14:54:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:54:27 INFO - Installing collected packages: blessings 14:54:27 INFO - Running setup.py install for blessings 14:54:27 INFO - Successfully installed blessings 14:54:27 INFO - Cleaning up... 14:54:27 INFO - Return code: 0 14:54:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:54:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:27 INFO - Reading from file tmpfile_stdout 14:54:27 INFO - Current package versions: 14:54:27 INFO - blessings == 1.6 14:54:27 INFO - blobuploader == 1.2.4 14:54:27 INFO - docopt == 0.6.1 14:54:27 INFO - manifestparser == 1.1 14:54:27 INFO - mozInstall == 1.12 14:54:27 INFO - mozcrash == 0.17 14:54:27 INFO - mozdebug == 0.1 14:54:27 INFO - mozdevice == 0.48 14:54:27 INFO - mozfile == 1.2 14:54:27 INFO - mozhttpd == 0.7 14:54:27 INFO - mozinfo == 0.9 14:54:27 INFO - mozleak == 0.1 14:54:27 INFO - mozlog == 3.2 14:54:27 INFO - moznetwork == 0.27 14:54:27 INFO - mozprocess == 0.22 14:54:27 INFO - mozprofile == 0.28 14:54:27 INFO - mozrunner == 6.11 14:54:27 INFO - mozscreenshot == 0.1 14:54:27 INFO - mozsystemmonitor == 0.1 14:54:27 INFO - moztest == 0.7 14:54:27 INFO - mozversion == 1.4 14:54:27 INFO - psutil == 3.1.1 14:54:27 INFO - requests == 1.2.3 14:54:27 INFO - wsgiref == 0.1.2 14:54:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:27 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 0x10d5bea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe641c9e260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, '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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:54:27 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 14:54:27 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 14:54:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:54:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:27 INFO - 'HOME': '/Users/cltbld', 14:54:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:27 INFO - 'LOGNAME': 'cltbld', 14:54:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:27 INFO - 'MOZ_NO_REMOTE': '1', 14:54:27 INFO - 'NO_EM_RESTART': '1', 14:54:27 INFO - 'PAGER': '/bin/cat', 14:54:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:27 INFO - 'PWD': '/builds/slave/test', 14:54:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:27 INFO - 'SHELL': '/bin/bash', 14:54:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:54:27 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:54:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:27 INFO - 'USER': 'cltbld', 14:54:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:27 INFO - 'XPC_FLAGS': '0x0', 14:54:27 INFO - 'XPC_SERVICE_NAME': '0', 14:54:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:28 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)) 14:54:28 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)) 14:54:28 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)) 14:54:28 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)) 14:54:28 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)) 14:54:28 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)) 14:54:28 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)) 14:54:28 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)) 14:54:28 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)) 14:54:28 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)) 14:54:28 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)) 14:54:28 INFO - Cleaning up... 14:54:28 INFO - Return code: 0 14:54:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:28 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 0x10d5bea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe641c9e260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, '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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:54:28 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 14:54:28 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 14:54:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:54:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:28 INFO - 'HOME': '/Users/cltbld', 14:54:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:28 INFO - 'LOGNAME': 'cltbld', 14:54:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:28 INFO - 'MOZ_NO_REMOTE': '1', 14:54:28 INFO - 'NO_EM_RESTART': '1', 14:54:28 INFO - 'PAGER': '/bin/cat', 14:54:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:28 INFO - 'PWD': '/builds/slave/test', 14:54:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:28 INFO - 'SHELL': '/bin/bash', 14:54:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:54:28 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:54:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:28 INFO - 'USER': 'cltbld', 14:54:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:28 INFO - 'XPC_FLAGS': '0x0', 14:54:28 INFO - 'XPC_SERVICE_NAME': '0', 14:54:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:28 INFO - Downloading/unpacking jsonschema 14:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:54:30 INFO - Downloading/unpacking functools32 (from jsonschema) 14:54:30 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 14:54:30 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 14:54:30 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 14:54:30 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 14:54:30 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 14:54:30 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 14:54:31 INFO - Downloading functools32-3.2.3-2.tar.gz 14:54:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:54:31 INFO - warning: no files found matching '*.txt' 14:54:31 INFO - no previously-included directories found matching 'build' 14:54:31 INFO - no previously-included directories found matching 'dist' 14:54:31 INFO - no previously-included directories found matching '.git*' 14:54:31 INFO - Installing collected packages: jsonschema, functools32 14:54:31 INFO - Running setup.py install for functools32 14:54:31 INFO - warning: no files found matching '*.txt' 14:54:31 INFO - no previously-included directories found matching 'build' 14:54:31 INFO - no previously-included directories found matching 'dist' 14:54:31 INFO - no previously-included directories found matching '.git*' 14:54:31 INFO - Successfully installed jsonschema functools32 14:54:31 INFO - Cleaning up... 14:54:31 INFO - Return code: 0 14:54:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:31 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 0x10d5bea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5c4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe641c9e260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, '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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 14:54:31 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 14:54:31 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 14:54:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:54:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:54:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:54:31 INFO - 'HOME': '/Users/cltbld', 14:54:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:54:31 INFO - 'LOGNAME': 'cltbld', 14:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:31 INFO - 'MOZ_NO_REMOTE': '1', 14:54:31 INFO - 'NO_EM_RESTART': '1', 14:54:31 INFO - 'PAGER': '/bin/cat', 14:54:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:31 INFO - 'PWD': '/builds/slave/test', 14:54:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:54:31 INFO - 'SHELL': '/bin/bash', 14:54:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:54:31 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:54:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:54:31 INFO - 'USER': 'cltbld', 14:54:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:54:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:31 INFO - 'XPC_FLAGS': '0x0', 14:54:31 INFO - 'XPC_SERVICE_NAME': '0', 14:54:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:31 INFO - Downloading/unpacking flake8 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:54:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:54:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:54:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54: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 14:54:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:54:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:54:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:54:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:54:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:54:35 INFO - no previously-included directories found matching 'docs/_build' 14:54:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:54:35 INFO - Running setup.py install for pep8 14:54:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:54:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:54:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:54:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:54:35 INFO - no previously-included directories found matching 'docs/_build' 14:54:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:54:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:54:35 INFO - Cleaning up... 14:54:35 INFO - Return code: 0 14:54:35 INFO - Running post-action listener: _resource_record_post_action 14:54:35 INFO - Running post-action listener: _start_resource_monitoring 14:54:35 INFO - Starting resource monitoring. 14:54:35 INFO - ##### 14:54:35 INFO - ##### Finished create-virtualenv step (success) 14:54:35 INFO - ##### 14:54:35 INFO - ##### 14:54:35 INFO - ##### Running install step. 14:54:35 INFO - ##### 14:54:35 INFO - Running pre-action listener: _resource_record_pre_action 14:54:35 INFO - Running main action method: install 14:54:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:36 INFO - Reading from file tmpfile_stdout 14:54:36 INFO - Detecting whether we're running mozinstall >=1.0... 14:54:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:54:36 INFO - Reading from file tmpfile_stdout 14:54:36 INFO - Output received: 14:54:36 INFO - Usage: mozinstall [options] installer 14:54:36 INFO - Options: 14:54:36 INFO - -h, --help show this help message and exit 14:54:36 INFO - -d DEST, --destination=DEST 14:54:36 INFO - Directory to install application into. [default: 14:54:36 INFO - "/builds/slave/test"] 14:54:36 INFO - --app=APP Application being installed. [default: firefox] 14:54:36 INFO - mkdir: /builds/slave/test/build/application 14:54:36 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'] 14:54:36 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 14:55:10 INFO - Reading from file tmpfile_stdout 14:55:10 INFO - Output received: 14:55:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:55:10 INFO - Running post-action listener: _resource_record_post_action 14:55:10 INFO - ##### 14:55:10 INFO - ##### Finished install step (success) 14:55:10 INFO - ##### 14:55:10 INFO - ##### 14:55:10 INFO - ##### Running run-tests step. 14:55:10 INFO - ##### 14:55:10 INFO - Running pre-action listener: _resource_record_pre_action 14:55:10 INFO - Running main action method: run_tests 14:55:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:55:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:55:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:55:10 INFO - 2016-05-13 14:55:10.396 screenresolution[1917:10684] starting screenresolution argv=screenresolution get 14:55:10 INFO - 2016-05-13 14:55:10.399 screenresolution[1917:10684] Display 0: 1600x1200x32@60 14:55:10 INFO - 2016-05-13 14:55:10.404 screenresolution[1918:10686] starting screenresolution argv=screenresolution list 14:55:10 INFO - Available Modes on Display 0 14:55:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:55:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:55:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:55:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:55:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:55:10 INFO - Intel Iris: 14:55:10 INFO - Chipset Model: Intel Iris 14:55:10 INFO - Type: GPU 14:55:10 INFO - Bus: Built-In 14:55:10 INFO - VRAM (Dynamic, Max): 1536 MB 14:55:10 INFO - Vendor: Intel (0x8086) 14:55:10 INFO - Device ID: 0x0a2e 14:55:10 INFO - Revision ID: 0x0009 14:55:10 INFO - Displays: 14:55:10 INFO - Display: 14:55:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:55:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:55:10 INFO - Display Serial Number: 1600x1200 60 14:55:10 INFO - Main Display: Yes 14:55:10 INFO - Mirror: Off 14:55:10 INFO - Online: Yes 14:55:10 INFO - Rotation: Supported 14:55:10 INFO - Return code: 0 14:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:55:10 INFO - Python 2.7.3 14:55:10 INFO - Return code: 0 14:55:10 INFO - grabbing minidump binary from tooltool 14:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:55:10 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d462d30>, '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 0x10d286db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d459b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:55:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:55:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:55:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:55:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:55:10 INFO - Return code: 0 14:55:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:55:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:55:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:55:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:55:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:55:11 INFO - Return code: 0 14:55:11 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/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 14:55:11 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/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0131 --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 14:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eIj4RUTPwO/Render', 14:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:55:11 INFO - 'HOME': '/Users/cltbld', 14:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:55:11 INFO - 'LOGNAME': 'cltbld', 14:55:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:55:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:55:11 INFO - 'MOZ_NO_REMOTE': '1', 14:55:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:55:11 INFO - 'NO_EM_RESTART': '1', 14:55:11 INFO - 'PAGER': '/bin/cat', 14:55:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:55:11 INFO - 'PWD': '/builds/slave/test', 14:55:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:55:11 INFO - 'SHELL': '/bin/bash', 14:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wh9bE5rBcr/Listeners', 14:55:11 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 14:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:55:11 INFO - 'USER': 'cltbld', 14:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:55:11 INFO - 'XPC_FLAGS': '0x0', 14:55:11 INFO - 'XPC_SERVICE_NAME': '0', 14:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:55:11 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/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 14:55:12 INFO - mozversion application_buildid: 20160513135736 14:55:12 INFO - mozversion application_changeset: edcd8d596806c757423acc2573c9047c569fb4f8 14:55:12 INFO - mozversion application_display_name: Nightly 14:55:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:55:12 INFO - mozversion application_name: Firefox 14:55:12 INFO - mozversion application_remotingname: firefox 14:55:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:55:12 INFO - mozversion application_vendor: Mozilla 14:55:12 INFO - mozversion application_version: 49.0a1 14:55:12 INFO - mozversion platform_buildid: 20160513135736 14:55:12 INFO - mozversion platform_changeset: edcd8d596806c757423acc2573c9047c569fb4f8 14:55:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:55:12 INFO - mozversion platform_version: 49.0a1 14:55:12 INFO - using testdate: 1463176512 14:55:12 INFO - actual date: 1463176512 14:55:12 INFO - starting webserver on 'localhost:49747' 14:55:12 INFO - SUITE-START | Running 2 tests 14:55:12 INFO - TEST-START | dromaeo_css 14:55:12 INFO - operating with platform_type : mac_ 14:55:12 INFO - Initialising browser for dromaeo_css test... 14:55:12 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpFYj2SL/profile http://localhost:49747/getInfo.html) 14:55:14 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 14:55:14 INFO - PROCESS | 1936 | colorDepth:24 14:55:14 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 14:55:14 INFO - PROCESS | 1936 | __metrics 14:55:14 INFO - PROCESS | 1936 | 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" 14:55:14 ERROR - PROCESS | 1936 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:55:15 INFO - TEST-INFO | 1936: exit 0 14:55:15 INFO - Browser initialized. 14:55:15 INFO - Running cycle 1/1 for dromaeo_css test... 14:55:15 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpFYj2SL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:55:27 INFO - PROCESS | 1938 | RSS: Main: 238948352 14:55:27 INFO - PROCESS | 1938 | 14:56:18 INFO - PROCESS | 1938 | 1463176578141 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] 14:58:28 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49747/tests/dromaeo/cssquery-ext.html) 14:58:29 INFO - PROCESS | 1938 | RSS: Main: 352743424 14:58:29 INFO - PROCESS | 1938 | 15:01:32 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-ext.html (next: http://localhost:49747/tests/dromaeo/cssquery-jquery.html) 15:01:33 INFO - PROCESS | 1938 | RSS: Main: 374947840 15:01:33 INFO - PROCESS | 1938 | 15:04:35 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49747/tests/dromaeo/cssquery-mootools.html) 15:04:36 INFO - PROCESS | 1938 | RSS: Main: 368132096 15:04:36 INFO - PROCESS | 1938 | 15:07:38 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49747/tests/dromaeo/cssquery-prototype.html) 15:07:39 INFO - PROCESS | 1938 | RSS: Main: 363610112 15:07:39 INFO - PROCESS | 1938 | 15:10:42 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49747/tests/dromaeo/cssquery-yui.html) 15:10:43 INFO - PROCESS | 1938 | RSS: Main: 331812864 15:10:43 INFO - PROCESS | 1938 | 15:13:46 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-yui.html (next: http://localhost:49747/tests/dromaeo/cssquery-dojo.html) 15:13:46 INFO - PROCESS | 1938 | RSS: Main: 376164352 15:13:46 INFO - PROCESS | 1938 | 15:13:46 INFO - PROCESS | 1938 | __start_tp_report 15:13:46 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 15:13:46 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 15:13:46 INFO - PROCESS | 1938 | |i|pagename|runs| 15:13:46 INFO - PROCESS | 1938 | |0;dojo.html;2671.639328690627;2953.1731115287726;3406.386850366933;3427.8457469413884;3434.6393628668975;5573.136163894597;5642.943570564293;5644.181593668915;5661.292338457688;5689.857753556153;5711.114777209529;5814.476697097271;5821.53427725782;5826.096954971976;5827.242458480402;5058.064258112261;5087.211482220243;5145.459726728695;5155.58755299577;5218.112920803473;5188.844334669966;5231.424543300234;5257.185136303879;5260.158374660055;5275.155975043994;5063.291139240528;5121.564667003309;5125.923111153335;5127.641065125443;5155.458676838915;4322.50291216511;5072.188449848043;5088.160453525164;5173.249878767558;5177.171652535614;5043.747812609392;5068.771905264272;5096.694198348111;5141.74291285438;5188.325517682677;5461.672299662033;5554.555635549187;5594.720263986785;5647.661140331593;5673.943260567383;6139.140520327158;6174.536909731743;6188.350223226541;6242.406971337716;6272.968635156795;5266.552343050846;6286.559940804145;6365.649889256089;6378.840528986812;6379.872402551923;5641.858953526113;5730.627509211888;5785.971070144622;5869.2369991901205;5870.11948207768;6309.716062777186;6323.30443651198;6330.620162790247;6332.018537126753;6334.049892516067;6129.31443852944;6132.172156758869;6132.172156758869;6133.228064740242;6169.876602467925;6093.360197179321;6100.762843515373;6168.691565421711;6253.0620406938415;6311.495080393557;6102.51954821894;6187.3503282154725;6213.937860621426;6220.409061139185;6223.813285601439;6148.9692551536955;6198.597091189058;6242.656653883989;6244.250689917239;6270.717817698214;4759.952400476021;4820.590249828733;4882.609391248691;4895.951040489621;4920.335754673145;4177.788441351974;4249.426327445817;4373.037931655031;4383.824647014084;4384.386185933972;5640.153976903497;5665.5184309333345;5673.404292549306;5694.402087780723;5739.655620662774;6114.358196333954;6139.938600613937;6285.811425657238;6293.37066293367;6294.496440284766;5602.467765562265;5618.185363122325;5640.661560306395;5646.661200327993;5657.943420565742;5291.523762861284;5294.179402192666;5304.496072873071;5307.787688492417;5308.814191503356;6253.749850006131;6261.5616906816085;6293.716782744787;6301.212348456443;6312.810615681537;6239.251289845248;6341;6351;6354.078658594385;6362.904556431565;6291.402316779899;6316.05259211122;6339.651319177489;6339.778107766206;6579.111819904443;6000.0999850022845;6040.214772079602;6084.269887613515;6133.723982420891;6162.352952939877;6153.692315384303;6177.96911015442;6240.188775459162;6246.3441338660605;6255.937440625536;5200.713960732196;6023.939760602338;6036.939630603638;6038.094285856981;6045.788397406246;5840.678762668094;5883.823485295448;5964.403559644001;5997.850053748692;6013.488853447506;5872.794452194153;5891.705414729332;5903.734331955158;5928.970355148197;5935.465808077293;5991.7902873399225;6084.316360559349;6092.939070609237;6111.502681842977;6131.601445906002;4939.827106051271;5771.365149833431;5888.587798854039;5891.852703682443;5935.732892019783 15:13:46 INFO - PROCESS | 1938 | |1;ext.html;499.72569946636094;513.6122227718074;515.8014164546648;519.5817367019549;577.1832856508042;30349;41084.589154108486;42560.14879702404;42572.57427425725;45008.77495612526;43742.7812860936;44074.77962610182;44122.7793861031;44134;44226.778866105626;8717.171868672465;9649.565769540352;9831.65589204378;9845.55694993727;9846.950765246163;8844.513551754651;10370.533326000348;11096.944515277453;11537.250078744897;11718.648440546796;11730;11823.408829558532;11823.822642660323;11836.112291578123;11839.052875769961;8752.47485150893;11606;11712.004479619158;11727.237729547594;11843.348615826084;11680.41597920109;11772.882271177265;11782.705432364173;11783.175177737561;11794.52821887132;40037.39943900844;40480;40523;40545;40563.39154912678;2580.895986602071;3220.0983724556927;3717.683996860243;3869.6323849234277;3950.604939506055;28330.163408780765;28349.85825070882;28682.426351473063;28688.282792930135;28861.56707649387;2704.2157774245447;2718.660167479065;2725.645666063399;2742.3829638331417;2811.4096039831456;481233;482112;482278;482361;482461;58829.70585147047;59234.70382648059;59662.70168649216;59807;59835.700821495615;35575.509714320266;36371.63628363683;62185.689071554356;62394.688026560485;62414.375856241764;16838.410655626994;16913.985160890385;16959.830401696072;16962;17014.234359453607;11767.293962362286;11936.10479214045;11952.641420757218;12169.391530423443;12922.41849116792;17263.913680431768;19383.806161938483;19500.1069848681;20040.799592004187;20042.89978550098;8170.550619715973;8179.631916563769;8186.508809471451;8216.917830821616;8238.546879921541;937.4796987671771;1009.3893194617298;1043.7181960870523;1073.2111897755144;1078.8219943709357;29507.409851802844;29795.851020744758;30103.548446773315;30385.392292154032;30469;1065.27957348881;1068.8129577323937;1094.8084085285043;1150.3270586706703;1203.8615559210627;15467.5359739208;15472.690546189238;15516;15684.843151568566;15694.843051569567;668140;673383;673458;675025;677997;58862.41137588655;60744.696276518334;60785.392146077975;60873;60924.69537652283;1201.9158658893969;1320.18148747777;1367.3641756583038;1462.4808193091299;1496.8203815542204;29987.355753131837;30291.545626816056;30587.54118688177;30886.073417797503;31506.842465787526;59129;59998.70000649969;60006;60098.39901600928;60125.699371502866;588387;609737;611042;611506;612023;33321.83339083289;33581.83209083939;35888.641113589576;35937.28125437529;36925.97183435104;43655.56344436515;46191;46484.53515464938;46926.76536617295;47020;37437.81281093577;37546;37992.810035949646;38798;39090.80454597709;31654;36824.26351473009;37894.81052594719;38546.6145338543;38648.033799155244;37216;37710.62289377071;38165.04587385338;38571.2285754289;38897.027574310865;32417.83791081124;32530.02409927706;32747.34505309928;32800.83599581987;33073.669263308024;20863.582728345045;24556.75443245616;24612.753872461046;25154.874225628755;25290.241292761246 15:13:46 INFO - PROCESS | 1938 | |2;jquery.html;356.4706410979695;360.0817914318488;374.27951193951634;387.11544121681965;401.86336645540547;20398.490037749045;22508.212212572562;22594.426255689526;22606;22890.313290601287;21066.84132372719;21351.57296854062;22081.779182208174;22635.547289054208;22731.090756369766;5819.563532735051;6013.849653758647;6167.414095660905;6175.475084617813;6223.844403889916;4261.552536983619;4476.2390393632995;6032.728527216286;6413.486921046305;6794.796156115324;5973.820785376446;6353.142325786029;6742.2246441659345;6761.0196521504595;6784.559003664771;6516.478681705452;6694.698738556776;6720.865582688368;6727.8654426911435;6771.72913083476;5251.816186433494;5589.965856316594;5607.775688972436;5838.095095260199;5865.266841644794;13745.450181992708;13972.86027139736;14025.368858401396;18584.90707546467;26677.599836002475;2095.8428117891067;2221.944451388712;2225.3419119288496;2254.9549009019893;2867.225849020753;9632.373895696757;10124.98750124989;10235.87405385407;12231.837975392324;14749.852501474956;1368.589423173044;1718.8710846686424;1722.9310827566883;1759.7888253409544;1974.299123811055;113996.29005564924;119189;122291.99999999911;122697;124654.37672811668;10701.036906678437;11152.275102118336;11702.88297117035;11745.412729363497;11925.88074119265;15118.773218401733;15253.542393728207;15263.771043434357;15269.923650381677;15284.38862445512;7905.56519391439;8537.317014638815;8794.91205087941;8984.8652270216;9096.135867092748;8900.884690358453;8903.287736981027;8903.866442003375;9026.413283136706;9058.728238152866;3870.96129038706;4612.584867361954;4693.507181745936;4752.643551733634;4762.52374762521;1795.2280519376532;1814.4338012092976;1839.5099969025132;1965.4791480257743;2007.5884443689008;888.2671795768517;953.9427634341962;972.2513664478454;1006.7479860085788;1018.6791160784329;20272.695909561367;20356.592868142554;21329.78670213309;21607.67588486174;21633.72398110916;923.886716506608;930.4370855632245;978.9167920726676;980.578351308944;1064.9041586257274;10849.403282819363;11257.380844053492;11520.711982200344;11558.711032224093;11593;596259;598990.0555469492;600195;618980;619966.9001655054;132187;134377;135052.64947350597;135490;136465.317673411;1014.8782146142363;1037.9377237365782;1056.3186744549723;1064.1858977881927;1066.3655125200494;17830.91084544569;18359.908200459446;18566.700330976702;18976.956267405425;19359.22563097517;44643.88390290139;49652.83792009478;51170;54954;55566.44433555612;80578.98552536;81576.59211704141;81801;81987.5900620493;82726.17273827185;8224.588770561568;8351.540665263468;8359.832803343777;8485.066642669424;8670.462887640959;9449.433034017706;12563.18339307942;12976.805347919977;13366.599002029954;13396.732065358443;10970.835437468284;11334.376609286239;11655.825162622397;11716.06271498261;11754.23597466162;9585.424874507551;11025.669229922794;11252.130706669746;11422.371769552423;11615.186936914673;8283.37874659391;11671.766564668489;11777.528898844294;11862.347570883685;11922.403879806148;6874.312568743086;7590.582517961565;7598.0502437195355;8481.745547633582;8680.262177714965;9528.666496672584;11530.827037594276;11556.94221528887;11601.709957251136;11767.52929882795 15:13:46 INFO - PROCESS | 1938 | |3;mootools.html;1461.8830493560515;1629.6985057764314;1658.8424099710528;1927.5470264487842;2015.5192013047988;8178.345732341413;8851.822963540726;9126.589303481343;9138.314626403013;9166.220871225938;8670.95664521678;8981.461112333265;8982.910170898289;9077.818443631124;9078.682246121387;2405.711314642241;2460.015993602555;2461.507698460306;2462.445949661321;2463.815213858959;2387.8686672233107;2453.754624537541;2457.0785955266774;2457.6313552967017;2463.3964678653756;2015.9496012599657;2407.5786737320987;2407.9759202408013;2452.4849781545895;2452.6934133233344;2388.319328991238;2416.2147302126878;2427.017058091476;2430.9270721878283;2461.7415171406924;2372.3950392649936;2441.926742197737;2469.5307891500556;2469.938251543708;2470.5676506611453;8095.716649917284;8370.748877533684;9010.14403631654;9086.045965173627;9099.590518426621;5344.171653393731;5564.693941833198;5564.944350556483;5595.38450770415;5600.52395546379;3510.508528805969;3665;3709.2025214578994;3715.108373990223;3743.382341913581;1040.47976011994;1053.5891002508952;1066.1044722433116;1067.8932106789337;1072.1476426241156;12052.337121458317;12300.138990270685;12303.630891073282;12321.075919306088;12330.136890417674;4919.557239848431;4956.330895329084;5048.343715316985;5062.417821950449;5091.312672789126;10617.309874858109;10661.093807026335;10678;10686.14510839131;10695.037446629685;5645.887082258332;5649.067903795828;5652.293463317085;5655.406182350794;5656.943430565724;4950.133726597831;5032.647714660012;5062.670926389773;5088.982203559303;5131.384233891882;3952.26883026641;4012.759234445884;4062.2687916175373;4125.628693417607;4137.46212992309;1300.5318085489216;1306.7255876265901;1315.3620494060374;1336.1381908669048;1340.537514557476;798.3054742374171;836.5190015740973;894.3113802372137;929.8977112517617;930.9813803723888;5692.14617807324;5922.082077278029;6069.453749162597;6080.331163572004;6095.299040610298;452.5723191583987;467.6235630317559;479.30740080583917;492.24194740099847;494.75262368815595;3394.9151271218416;3813.56144043433;3982.681385489154;4039.6364327210104;4088.12105397341;11026;11344.376059316817;11351.716207094889;11352.94323528377;11378.203525753283;10837.83743243852;11241.83137252942;11251.212415130862;11259.211855170222;11304;8379.162083791573;8489.235968762841;8548.401611887233;8581.227689507974;8623.223909848144;5163.638545301793;5978.491828194652;6017.699115044318;6125.081237814364;6146.955017646921;10063.295569310078;10172.949135254275;10524.947375263075;10539.78920421572;10613.150947924301;8537.658493660432;10316.226283028953;10402.063814256753;10623.256372053882;10722.571097156026;7547.886781698388;7832.960835195788;7840.803979900548;7845.568493732671;7854.136045035156;7108.898120791286;8430.536320502431;8600.483970961763;8607.18231767981;8608.741737747878;7724.382049435919;7806.414518911218;7819.296263336327;7854.842903142019;7976.322012628992;7494.138174110047;7645.426593005435;7669.693212271447;7722.575258360844;7773.611319434119;6955.269696681978;7237.819054523468;7296.233895441114;7306.050213472215;7314.634268286671;3921.627445392683;5035.043341765053;5055.443901170795;5103.10695628271;5183.222516622537;4210.926213815457;5057.924131137962;5130.97434512825;5134.358205224347;5345.759440825094 15:13:46 INFO - PROCESS | 1938 | |4;prototype.html;268.93545549068216;306.25274330633255;315.27486780405064;324.14749209578787;325.66293885828173;4595.839145629903;5277.577793776498;5325.973370133149;5336.4129945705945;5355.919661205085;4586.908261834761;4680.923387620849;5108.773894265368;5246.580273578114;5311.787528498855;3144.2453811085347;3543.875964341236;3843.2121415109973;4049.9190016199805;4053.3109371406786;3969.801509924492;3982.0443093657605;4015.638592526672;4026.5168179818315;4038.8788336349958;3474.724055743492;3856.7465573688523;3964.8612298569556;3976.7216294859372;3998.920021599566;3285.622153452348;3771.6416940390754;3895.162540053902;3998.820053097618;4015.859444919443;3487.1169568433015;3504.4918486819524;3504.842282097312;3515.490253913168;3531.4173161428334;4003.5195776506716;4730.0370346306245;5038;5042.966191930645;5064.898702025976;3721.8697345592773;4166.625003749647;4182.079942412664;4525.389072475206;4537;5457.345118585756;5467.535259502947;5524.066576698124;5528.474794894479;5536.335639723218;3519.348920449725;3781;3797.259534390795;3806.885793426202;3817.2174704185577;5546.889062218774;5701.315842098892;5770.336411312711;5771.797987070432;5788.507976821975;3557.5375201223674;3798.639129282714;3825.2977425046142;3848;3862.594427585063;3782.678472329883;3899.3038028457713;3913.8434462621176;3919.2749341371937;3939.783311917815;3953.367461206193;4339.284018136972;4388.517263101056;4500.099980003947;4526;4398.75806830621;4466.910661786811;4904.435989861212;5549.250851135126;5551.417101204315;1295.5077070713069;1319.4392383237087;1326.4083100279704;1331.3276795218546;1338.3605083772302;773.8258891749311;798.5448294472211;820.24127681894;825.5542007316102;831.9459235149695;4390.912181756347;4943.530364615356;5007.474215207423;5087.465816089258;5107.387113546398;4823.782929768169;5432.67403955756;5662.886742265132;5664.291963504562;5742.253507044057;4836.274558816206;5101.617378696613;5114.974425127925;5120.667156634807;5129.9743501282255;2509.3224829296355;2923.9122826315242;2948.6461624605186;2949.4810172761045;2969.658489273761;4978.440415103219;5571.272905399258;5722.885542289214;5735.139729040678;5895.7052147393315;4415.7856589437515;4957.702537847741;5119.667221630582;5788.363280039192;5826.41735826414;5187.766550505161;5286.788528458818;5627.582347856913;5739.71301434935;5903.439173278532;5147.639665223548;5586.972065139648;5606.981486664716;5613.354464236665;5643.830685079454;5051.520105589963;5348.57211423093;5483.698396588226;5503.917441238465;5554.888902221852;5330.573554115739;5802.796902108406;5809.738561764646;5824.271966004249;5854.677992710442;3138.2154461384653;3610.0586953535267;3643.198496330803;3784.905377365588;3803.8098095094583;4602.608778253886;4949.505049495022;5605.411431799603;5605.831825045255;5705.201271822031;4857.417109946829;5437.728113594384;5445.264889239902;5477.548449660922;5485.915352166151;4876.024795040935;4900.362952816111;5371.301730774974;5488.231647569263;5489.3687225969525;4712.410948631421;5113.823820521376;5421.7560209791445;5451.945480545144;5469;4591.908161836812;4863.990090207092;5127.5897928166405;5258.631895767413;5543.77824887;4795.616350692006;4994.101061808909;5403.756830942696;5406.621536492407;5476.835694929158 15:13:46 INFO - PROCESS | 1938 | |5;yui.html;618.3414663383497;724.4494184419839;752.5935994562934;753.077480086894;753.9924600753994;14991.700165996674;15140.394384224617;15661.921690391531;15682.529524114281;15916.681666366694;14519.49181778638;14566.344514496846;14572.125672459633;14611.342489587967;14623.268836558183;739.345679074018;934.2152591822851;943.2406912435487;953.4422362917702;953.5756588318201;753.9472236943416;908.318760929303;931.1154403316842;931.7065124485765;932.7668082979255;982.7887004294075;987.856760769688;989.9851502227436;990.6384169778015;992.007992007992;770.5915864591719;790.5059337913804;982.1700662939804;982.4400091947592;984.517586382671;593.3710267116827;593.4540222994855;594.2898236607256;718.6263143165525;735.3675838778646;14961.326740296712;15002.924985375112;15016.023958442665;15016.849831501655;15030.624234394227;12178.695532611668;12382.257064576153;12425.068119891044;12507.93746031264;12712.3008234548;10000.299979001473;10357.016083472134;10495.317804342769;10520.684379468628;10717;4021.7586944783407;4108.876733697994;4215.515215750197;4238.745675259494;4330.328799036154;84705.57647211786;88044.23911521798;88944.55527722384;89157;89540.55229723876;7328.853422931512;9409.388389754644;9420;9642.951785241028;9966.754155730534;20680.069396877177;20783.792162078487;20795.896020520104;20837;20867;7145.499815013003;7274.345308922222;7442.813929651802;7509.399248060142;7546.396288296923;3147.3233254850315;3935.2129574085297;3943.743656662308;3962.5046869141356;3978.0850404407147;3225.4033003894356;3453.309338132384;3483.3381657484997;3577.5349204603754;3583.5341405617623;629.9559030867887;631.782035197856;652.1522021372292;652.7584793626388;655.8786624474487;500.83555732921104;514.9819756308511;610.3957082488394;619.3342157180958;628.2398298059308;6892.517523773287;7413.703451861973;7865.134835168127;8008.439409241297;8110.716124935599;567.0841590830779;572.9197912292283;586.3420856178121;605.1014243847883;614.6895817612077;3732.5147730794824;3961.504811898513;3970.80145992705;4049.149678567475;4100.446439730598;92820;94435;98645;99995;100096.99903000877;43793.78103109464;69788.65105674439;75798;82598.1090661824;85565;78201;78261.82607260895;78631.8205226922;78735.60632196802;78747;6682.365175308338;7750.224977502205;7799.259070388304;7883.921160788319;8151.805212361697;47206.527934720216;47383;47754;47925.520744792106;48703.5129648699;49798;65733.35666608372;66223.33776662171;68416;69018.65490672716;10227.335223210468;10244.692659220234;10435.739106522398;10582.4708764563;10666.946665266623;10704.411257380918;11040.619922509686;11228.66314010581;11600.5359785611;11657.18399712012;10663.13050042722;10735.838962415726;10778.461076945965;10839.13286937059;10950.890491094988;9368.578413971523;10457.95420457948;10529.36823790575;10607;10764.461776910966;10596.258261921592;10602.310849794738;10738.140948723927;10892.346459212473;11541.480633371686;8843.867341990004;8944.195022448012;9060.456372617664;9234.399764015317;9375;8923.196912277926;8951;8988.280937525113;8994.82010359776;9085.227755640844 15:13:46 INFO - PROCESS | 1938 | __end_tp_report 15:13:46 INFO - PROCESS | 1938 | __start_cc_report 15:13:46 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1198 15:13:46 INFO - PROCESS | 1938 | __end_cc_report 15:13:46 INFO - PROCESS | 1938 | __startTimestamp1463177626888__endTimestamp 15:13:46 INFO - PROCESS | 1938 | 15:13:46 INFO - PROCESS | 1938 | ------- Summary: start ------- 15:13:46 INFO - PROCESS | 1938 | Number of tests: 6 15:13:46 INFO - PROCESS | 1938 | 15:13:46 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5689.05 Median:5888.59 stddev:689.66 (11.7%) stddev-sans-first:649.88 15:13:46 INFO - PROCESS | 1938 | Values: 2671.6 2953.2 3406.4 3427.8 3434.6 5573.1 5642.9 5644.2 5661.3 5689.9 5711.1 5814.5 5821.5 5826.1 5827.2 5058.1 5087.2 5145.5 5155.6 5218.1 5188.8 5231.4 5257.2 5260.2 5275.2 5063.3 5121.6 5125.9 5127.6 5155.5 4322.5 5072.2 5088.2 5173.2 5177.2 5043.7 5068.8 5096.7 5141.7 5188.3 5461.7 5554.6 5594.7 5647.7 5673.9 6139.1 6174.5 6188.4 6242.4 6273.0 5266.6 6286.6 6365.6 6378.8 6379.9 5641.9 5730.6 5786.0 5869.2 5870.1 6309.7 6323.3 6330.6 6332.0 6334.0 6129.3 6132.2 6132.2 6133.2 6169.9 6093.4 6100.8 6168.7 6253.1 6311.5 6102.5 6187.4 6213.9 6220.4 6223.8 6149.0 6198.6 6242.7 6244.3 6270.7 4760.0 4820.6 4882.6 4896.0 4920.3 4177.8 4249.4 4373.0 4383.8 4384.4 5640.2 5665.5 5673.4 5694.4 5739.7 6114.4 6139.9 6285.8 6293.4 6294.5 5602.5 5618.2 5640.7 5646.7 5657.9 5291.5 5294.2 5304.5 5307.8 5308.8 6253.7 6261.6 6293.7 6301.2 6312.8 6239.3 6341.0 6351.0 6354.1 6362.9 6291.4 6316.1 6339.7 6339.8 6579.1 6000.1 6040.2 6084.3 6133.7 6162.4 6153.7 6178.0 6240.2 6246.3 6255.9 5200.7 6023.9 6036.9 6038.1 6045.8 5840.7 5883.8 5964.4 5997.9 6013.5 5872.8 5891.7 5903.7 5929.0 5935.5 5991.8 6084.3 6092.9 6111.5 6131.6 4939.8 5771.4 5888.6 5891.9 5935.7 15:13:46 INFO - PROCESS | 1938 | 15:13:46 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:72117.44 Median:36154.46 stddev:158505.39 (438.4%) stddev-sans-first:158858.83 15:13:46 INFO - PROCESS | 1938 | Values: 499.7 513.6 515.8 519.6 577.2 30349.0 41084.6 42560.1 42572.6 45008.8 43742.8 44074.8 44122.8 44134.0 44226.8 8717.2 9649.6 9831.7 9845.6 9847.0 8844.5 10370.5 11096.9 11537.3 11718.6 11730.0 11823.4 11823.8 11836.1 11839.1 8752.5 11606.0 11712.0 11727.2 11843.3 11680.4 11772.9 11782.7 11783.2 11794.5 40037.4 40480.0 40523.0 40545.0 40563.4 2580.9 3220.1 3717.7 3869.6 3950.6 28330.2 28349.9 28682.4 28688.3 28861.6 2704.2 2718.7 2725.6 2742.4 2811.4 481233.0 482112.0 482278.0 482361.0 482461.0 58829.7 59234.7 59662.7 59807.0 59835.7 35575.5 36371.6 62185.7 62394.7 62414.4 16838.4 16914.0 16959.8 16962.0 17014.2 11767.3 11936.1 11952.6 12169.4 12922.4 17263.9 19383.8 19500.1 20040.8 20042.9 8170.6 8179.6 8186.5 8216.9 8238.5 937.5 1009.4 1043.7 1073.2 1078.8 29507.4 29795.9 30103.5 30385.4 30469.0 1065.3 1068.8 1094.8 1150.3 1203.9 15467.5 15472.7 15516.0 15684.8 15694.8 668140.0 673383.0 673458.0 675025.0 677997.0 58862.4 60744.7 60785.4 60873.0 60924.7 1201.9 1320.2 1367.4 1462.5 1496.8 29987.4 30291.5 30587.5 30886.1 31506.8 59129.0 59998.7 60006.0 60098.4 60125.7 588387.0 609737.0 611042.0 611506.0 612023.0 33321.8 33581.8 35888.6 35937.3 36926.0 43655.6 46191.0 46484.5 46926.8 47020.0 37437.8 37546.0 37992.8 38798.0 39090.8 31654.0 36824.3 37894.8 38546.6 38648.0 37216.0 37710.6 38165.0 38571.2 38897.0 32417.8 32530.0 32747.3 32800.8 33073.7 20863.6 24556.8 24612.8 25154.9 25290.2 15:13:46 INFO - PROCESS | 1938 | 15:13:46 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:35586.35 Median:12424.69 stddev:101487.37 (816.8%) stddev-sans-first:101737.59 15:13:46 INFO - PROCESS | 1938 | Values: 356.5 360.1 374.3 387.1 401.9 20398.5 22508.2 22594.4 22606.0 22890.3 21066.8 21351.6 22081.8 22635.5 22731.1 5819.6 6013.8 6167.4 6175.5 6223.8 4261.6 4476.2 6032.7 6413.5 6794.8 5973.8 6353.1 6742.2 6761.0 6784.6 6516.5 6694.7 6720.9 6727.9 6771.7 5251.8 5590.0 5607.8 5838.1 5865.3 13745.5 13972.9 14025.4 18584.9 26677.6 2095.8 2221.9 2225.3 2255.0 2867.2 9632.4 10125.0 10235.9 12231.8 14749.9 1368.6 1718.9 1722.9 1759.8 1974.3 113996.3 119189.0 122292.0 122697.0 124654.4 10701.0 11152.3 11702.9 11745.4 11925.9 15118.8 15253.5 15263.8 15269.9 15284.4 7905.6 8537.3 8794.9 8984.9 9096.1 8900.9 8903.3 8903.9 9026.4 9058.7 3871.0 4612.6 4693.5 4752.6 4762.5 1795.2 1814.4 1839.5 1965.5 2007.6 888.3 953.9 972.3 1006.7 1018.7 20272.7 20356.6 21329.8 21607.7 21633.7 923.9 930.4 978.9 980.6 1064.9 10849.4 11257.4 11520.7 11558.7 11593.0 596259.0 598990.1 600195.0 618980.0 619966.9 132187.0 134377.0 135052.6 135490.0 136465.3 1014.9 1037.9 1056.3 1064.2 1066.4 17830.9 18359.9 18566.7 18977.0 19359.2 44643.9 49652.8 51170.0 54954.0 55566.4 80579.0 81576.6 81801.0 81987.6 82726.2 8224.6 8351.5 8359.8 8485.1 8670.5 9449.4 12563.2 12976.8 13366.6 13396.7 10970.8 11334.4 11655.8 11716.1 11754.2 9585.4 11025.7 11252.1 11422.4 11615.2 8283.4 11671.8 11777.5 11862.3 11922.4 6874.3 7590.6 7598.1 8481.7 8680.3 9528.7 11530.8 11556.9 11601.7 11767.5 15:13:46 INFO - PROCESS | 1938 | 15:13:46 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5886.34 Median:493.50 stddev:3360.07 (680.9%) stddev-sans-first:3353.04 15:13:46 INFO - PROCESS | 1938 | Values: 1461.9 1629.7 1658.8 1927.5 2015.5 8178.3 8851.8 9126.6 9138.3 9166.2 8671.0 8981.5 8982.9 9077.8 9078.7 2405.7 2460.0 2461.5 2462.4 2463.8 2387.9 2453.8 2457.1 2457.6 2463.4 2015.9 2407.6 2408.0 2452.5 2452.7 2388.3 2416.2 2427.0 2430.9 2461.7 2372.4 2441.9 2469.5 2469.9 2470.6 8095.7 8370.7 9010.1 9086.0 9099.6 5344.2 5564.7 5564.9 5595.4 5600.5 3510.5 3665.0 3709.2 3715.1 3743.4 1040.5 1053.6 1066.1 1067.9 1072.1 12052.3 12300.1 12303.6 12321.1 12330.1 4919.6 4956.3 5048.3 5062.4 5091.3 10617.3 10661.1 10678.0 10686.1 10695.0 5645.9 5649.1 5652.3 5655.4 5656.9 4950.1 5032.6 5062.7 5089.0 5131.4 3952.3 4012.8 4062.3 4125.6 4137.5 1300.5 1306.7 1315.4 1336.1 1340.5 798.3 836.5 894.3 929.9 931.0 5692.1 5922.1 6069.5 6080.3 6095.3 452.6 467.6 479.3 492.2 494.8 3394.9 3813.6 3982.7 4039.6 4088.1 11026.0 11344.4 11351.7 11352.9 11378.2 10837.8 11241.8 11251.2 11259.2 11304.0 8379.2 8489.2 8548.4 8581.2 8623.2 5163.6 5978.5 6017.7 6125.1 6147.0 10063.3 10172.9 10524.9 10539.8 10613.2 8537.7 10316.2 10402.1 10623.3 10722.6 7547.9 7833.0 7840.8 7845.6 7854.1 7108.9 8430.5 8600.5 8607.2 8608.7 7724.4 7806.4 7819.3 7854.8 7976.3 7494.1 7645.4 7669.7 7722.6 7773.6 6955.3 7237.8 7296.2 7306.1 7314.6 3921.6 5035.0 5055.4 5103.1 5183.2 4210.9 5057.9 5131.0 5134.4 5345.8 15:13:46 INFO - PROCESS | 1938 | 15:13:46 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4408.48 Median:4946.52 stddev:1352.48 (27.3%) stddev-sans-first:1320.11 15:13:46 INFO - PROCESS | 1938 | Values: 268.9 306.3 315.3 324.1 325.7 4595.8 5277.6 5326.0 5336.4 5355.9 4586.9 4680.9 5108.8 5246.6 5311.8 3144.2 3543.9 3843.2 4049.9 4053.3 3969.8 3982.0 4015.6 4026.5 4038.9 3474.7 3856.7 3964.9 3976.7 3998.9 3285.6 3771.6 3895.2 3998.8 4015.9 3487.1 3504.5 3504.8 3515.5 3531.4 4003.5 4730.0 5038.0 5043.0 5064.9 3721.9 4166.6 4182.1 4525.4 4537.0 5457.3 5467.5 5524.1 5528.5 5536.3 3519.3 3781.0 3797.3 3806.9 3817.2 5546.9 5701.3 5770.3 5771.8 5788.5 3557.5 3798.6 3825.3 3848.0 3862.6 3782.7 3899.3 3913.8 3919.3 3939.8 3953.4 4339.3 4388.5 4500.1 4526.0 4398.8 4466.9 4904.4 5549.3 5551.4 1295.5 1319.4 1326.4 1331.3 1338.4 773.8 798.5 820.2 825.6 831.9 4390.9 4943.5 5007.5 5087.5 5107.4 4823.8 5432.7 5662.9 5664.3 5742.3 4836.3 5101.6 5115.0 5120.7 5130.0 2509.3 2923.9 2948.6 2949.5 2969.7 4978.4 5571.3 5722.9 5735.1 5895.7 4415.8 4957.7 5119.7 5788.4 5826.4 5187.8 5286.8 5627.6 5739.7 5903.4 5147.6 5587.0 5607.0 5613.4 5643.8 5051.5 5348.6 5483.7 5503.9 5554.9 5330.6 5802.8 5809.7 5824.3 5854.7 3138.2 3610.1 3643.2 3784.9 3803.8 4602.6 4949.5 5605.4 5605.8 5705.2 4857.4 5437.7 5445.3 5477.5 5485.9 4876.0 4900.4 5371.3 5488.2 5489.4 4712.4 5113.8 5421.8 5451.9 5469.0 4591.9 4864.0 5127.6 5258.6 5543.8 4795.6 4994.1 5403.8 5406.6 5476.8 15:13:46 INFO - PROCESS | 1938 | 15:13:46 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:18375.97 Median:607.75 stddev:26640.79 (4383.5%) stddev-sans-first:26682.15 15:13:46 INFO - PROCESS | 1938 | Values: 618.3 724.4 752.6 753.1 754.0 14991.7 15140.4 15661.9 15682.5 15916.7 14519.5 14566.3 14572.1 14611.3 14623.3 739.3 934.2 943.2 953.4 953.6 753.9 908.3 931.1 931.7 932.8 982.8 987.9 990.0 990.6 992.0 770.6 790.5 982.2 982.4 984.5 593.4 593.5 594.3 718.6 735.4 14961.3 15002.9 15016.0 15016.8 15030.6 12178.7 12382.3 12425.1 12507.9 12712.3 10000.3 10357.0 10495.3 10520.7 10717.0 4021.8 4108.9 4215.5 4238.7 4330.3 84705.6 88044.2 88944.6 89157.0 89540.6 7328.9 9409.4 9420.0 9643.0 9966.8 20680.1 20783.8 20795.9 20837.0 20867.0 7145.5 7274.3 7442.8 7509.4 7546.4 3147.3 3935.2 3943.7 3962.5 3978.1 3225.4 3453.3 3483.3 3577.5 3583.5 630.0 631.8 652.2 652.8 655.9 500.8 515.0 610.4 619.3 628.2 6892.5 7413.7 7865.1 8008.4 8110.7 567.1 572.9 586.3 605.1 614.7 3732.5 3961.5 3970.8 4049.1 4100.4 92820.0 94435.0 98645.0 99995.0 100097.0 43793.8 69788.7 75798.0 82598.1 85565.0 78201.0 78261.8 78631.8 78735.6 78747.0 6682.4 7750.2 7799.3 7883.9 8151.8 47206.5 47383.0 47754.0 47925.5 48703.5 49798.0 65733.4 66223.3 68416.0 69018.7 10227.3 10244.7 10435.7 10582.5 10666.9 10704.4 11040.6 11228.7 11600.5 11657.2 10663.1 10735.8 10778.5 10839.1 10950.9 9368.6 10458.0 10529.4 10607.0 10764.5 10596.3 10602.3 10738.1 10892.3 11541.5 8843.9 8944.2 9060.5 9234.4 9375.0 8923.2 8951.0 8988.3 8994.8 9085.2 15:13:46 INFO - PROCESS | 1938 | -------- Summary: end -------- 15:13:46 INFO - PROCESS | 1938 | 15:13:47 INFO - TEST-INFO | 1938: exit 0 15:13:47 INFO - TEST-OK | dromaeo_css | took 1115410ms 15:13:47 INFO - TEST-START | kraken 15:13:47 INFO - operating with platform_type : mac_ 15:13:47 INFO - Initialising browser for kraken test... 15:13:47 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmppkYg35/profile http://localhost:49747/getInfo.html) 15:13:49 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 15:13:49 INFO - PROCESS | 1944 | colorDepth:24 15:13:49 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 15:13:49 INFO - PROCESS | 1944 | __metrics 15:13:49 INFO - PROCESS | 1944 | 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" 15:13:49 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:13:50 INFO - TEST-INFO | 1944: exit 0 15:13:50 INFO - Browser initialized. 15:13:50 INFO - Running cycle 1/1 for kraken test... 15:13:50 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmppkYg35/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:14:02 INFO - PROCESS | 1946 | RSS: Main: 238915584 15:14:02 INFO - PROCESS | 1946 | 15:14:05 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49747/tests/kraken/driver.html?testName=audio-beat-detection) 15:14:06 INFO - PROCESS | 1946 | RSS: Main: 287178752 15:14:06 INFO - PROCESS | 1946 | 15:14:08 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49747/tests/kraken/driver.html?testName=audio-dft) 15:14:09 INFO - PROCESS | 1946 | RSS: Main: 296394752 15:14:09 INFO - PROCESS | 1946 | 15:14:13 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49747/tests/kraken/driver.html?testName=audio-fft) 15:14:13 INFO - PROCESS | 1946 | RSS: Main: 386662400 15:14:13 INFO - PROCESS | 1946 | 15:14:15 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49747/tests/kraken/driver.html?testName=audio-oscillator) 15:14:16 INFO - PROCESS | 1946 | RSS: Main: 284741632 15:14:16 INFO - PROCESS | 1946 | 15:14:18 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49747/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:14:19 INFO - PROCESS | 1946 | RSS: Main: 288370688 15:14:19 INFO - PROCESS | 1946 | 15:14:22 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49747/tests/kraken/driver.html?testName=imaging-darkroom) 15:14:23 INFO - PROCESS | 1946 | RSS: Main: 327520256 15:14:23 INFO - PROCESS | 1946 | 15:14:26 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49747/tests/kraken/driver.html?testName=imaging-desaturate) 15:14:26 INFO - PROCESS | 1946 | RSS: Main: 331042816 15:14:26 INFO - PROCESS | 1946 | 15:14:29 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49747/tests/kraken/driver.html?testName=json-parse-financial) 15:14:30 INFO - PROCESS | 1946 | RSS: Main: 329703424 15:14:30 INFO - PROCESS | 1946 | 15:14:32 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49747/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:14:32 INFO - PROCESS | 1946 | RSS: Main: 316624896 15:14:32 INFO - PROCESS | 1946 | 15:14:34 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:14:35 INFO - PROCESS | 1946 | RSS: Main: 323768320 15:14:35 INFO - PROCESS | 1946 | 15:14:37 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:14:38 INFO - PROCESS | 1946 | RSS: Main: 325218304 15:14:38 INFO - PROCESS | 1946 | 15:14:41 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:14:42 INFO - PROCESS | 1946 | RSS: Main: 329801728 15:14:42 INFO - PROCESS | 1946 | 15:14:45 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:14:45 INFO - PROCESS | 1946 | RSS: Main: 327651328 15:14:45 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49747/tests/kraken/driver.html?testName=ai-astar) 15:14:47 INFO - PROCESS | 1946 | RSS: Main: 374325248 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | __start_tp_report 15:14:47 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 15:14:47 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 15:14:47 INFO - PROCESS | 1946 | |i|pagename|runs| 15:14:47 INFO - PROCESS | 1946 | |0;ai-astar;113;106;112;113;111;113;112;112;112;111 15:14:47 INFO - PROCESS | 1946 | |1;audio-beat-detection;158;118;143;200;119;144;139;121;174;119 15:14:47 INFO - PROCESS | 1946 | |2;audio-dft;188;188;186;187;185;187;184;184;184;229 15:14:47 INFO - PROCESS | 1946 | |3;audio-fft;80;74;57;126;56;63;57;67;134;78 15:14:47 INFO - PROCESS | 1946 | |4;audio-oscillator;94;100;132;80;82;77;100;80;78;101 15:14:47 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;89;87;89;86;87;85;89;87;89;85 15:14:47 INFO - PROCESS | 1946 | |6;imaging-darkroom;94;94;94;95;92;94;94;94;94;94 15:14:47 INFO - PROCESS | 1946 | |7;imaging-desaturate;143;139;98;86;84;86;84;87;84;86 15:14:47 INFO - PROCESS | 1946 | |8;json-parse-financial;58;57;57;56;56;57;56;120;57;55 15:14:47 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;63;65;79;73;71;68;68;69;69;70 15:14:47 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;85;85;92;103;106;105;82;85;86;85 15:14:47 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;141;136;138;138;137;137;135;137;217;173 15:14:47 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;165;164;165;165;164;164;165;165;225;190 15:14:47 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;66;67;75;84;88;85;66;65;66;64 15:14:47 INFO - PROCESS | 1946 | __end_tp_report 15:14:47 INFO - PROCESS | 1946 | __start_cc_report 15:14:47 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,2347 15:14:47 INFO - PROCESS | 1946 | __end_cc_report 15:14:47 INFO - PROCESS | 1946 | __startTimestamp1463177687776__endTimestamp 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | ------- Summary: start ------- 15:14:47 INFO - PROCESS | 1946 | Number of tests: 14 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:2.07 (1.8%) stddev-sans-first:2.12 15:14:47 INFO - PROCESS | 1946 | Values: 113.0 106.0 112.0 113.0 111.0 113.0 112.0 112.0 112.0 111.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:143.50 stddev:27.31 (19.0%) stddev-sans-first:28.45 15:14:47 INFO - PROCESS | 1946 | Values: 158.0 118.0 143.0 200.0 119.0 144.0 139.0 121.0 174.0 119.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:190.20 Median:187.00 stddev:13.73 (7.3%) stddev-sans-first:14.54 15:14:47 INFO - PROCESS | 1946 | Values: 188.0 188.0 186.0 187.0 185.0 187.0 184.0 184.0 184.0 229.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:79.20 Median:65.00 stddev:28.20 (43.4%) stddev-sans-first:29.91 15:14:47 INFO - PROCESS | 1946 | Values: 80.0 74.0 57.0 126.0 56.0 63.0 57.0 67.0 134.0 78.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:79.00 stddev:17.06 (21.6%) stddev-sans-first:18.09 15:14:47 INFO - PROCESS | 1946 | Values: 94.0 100.0 132.0 80.0 82.0 77.0 100.0 80.0 78.0 101.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.00 stddev:1.64 (1.9%) stddev-sans-first:1.62 15:14:47 INFO - PROCESS | 1946 | Values: 89.0 87.0 89.0 86.0 87.0 85.0 89.0 87.0 89.0 85.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 15:14:47 INFO - PROCESS | 1946 | Values: 94.0 94.0 94.0 95.0 92.0 94.0 94.0 94.0 94.0 94.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:86.00 stddev:23.20 (27.0%) stddev-sans-first:17.91 15:14:47 INFO - PROCESS | 1946 | Values: 143.0 139.0 98.0 86.0 84.0 86.0 84.0 87.0 84.0 86.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:57.00 stddev:20.08 (35.2%) stddev-sans-first:21.22 15:14:47 INFO - PROCESS | 1946 | Values: 58.0 57.0 57.0 56.0 56.0 57.0 56.0 120.0 57.0 55.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.50 stddev:4.38 (6.3%) stddev-sans-first:3.96 15:14:47 INFO - PROCESS | 1946 | Values: 63.0 65.0 79.0 73.0 71.0 68.0 68.0 69.0 69.0 70.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:91.40 Median:85.00 stddev:9.51 (11.2%) stddev-sans-first:9.80 15:14:47 INFO - PROCESS | 1946 | Values: 85.0 85.0 92.0 103.0 106.0 105.0 82.0 85.0 86.0 85.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:138.00 stddev:26.46 (19.2%) stddev-sans-first:27.91 15:14:47 INFO - PROCESS | 1946 | Values: 141.0 136.0 138.0 138.0 137.0 137.0 135.0 137.0 217.0 173.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:165.00 stddev:19.88 (12.0%) stddev-sans-first:20.86 15:14:47 INFO - PROCESS | 1946 | Values: 165.0 164.0 165.0 165.0 164.0 164.0 165.0 165.0 225.0 190.0 15:14:47 INFO - PROCESS | 1946 | 15:14:47 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:71.00 stddev:9.55 (13.4%) stddev-sans-first:9.82 15:14:47 INFO - PROCESS | 1946 | Values: 66.0 67.0 75.0 84.0 88.0 85.0 66.0 65.0 66.0 64.0 15:14:47 INFO - PROCESS | 1946 | -------- Summary: end -------- 15:14:47 INFO - PROCESS | 1946 | 15:14:48 INFO - TEST-INFO | 1946: exit 0 15:14:48 INFO - TEST-OK | kraken | took 61151ms 15:14:48 INFO - SUITE-END | took 1176s 15:14:48 INFO - Completed test suite (00:19:36) 15:14:48 INFO - javascript benchmark 15:14:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2671.639328690627, 2953.1731115287726, 3406.386850366933, 3427.8457469413884, 3434.6393628668975, 5573.136163894597, 5642.943570564293, 5644.181593668915, 5661.292338457688, 5689.857753556153, 5711.114777209529, 5814.476697097271, 5821.53427725782, 5826.096954971976, 5827.242458480402, 5058.064258112261, 5087.211482220243, 5145.459726728695, 5155.58755299577, 5218.112920803473, 5188.844334669966, 5231.424543300234, 5257.185136303879, 5260.158374660055, 5275.155975043994, 5063.291139240528, 5121.564667003309, 5125.923111153335, 5127.641065125443, 5155.458676838915, 4322.50291216511, 5072.188449848043, 5088.160453525164, 5173.249878767558, 5177.171652535614, 5043.747812609392, 5068.771905264272, 5096.694198348111, 5141.74291285438, 5188.325517682677, 5461.672299662033, 5554.555635549187, 5594.720263986785, 5647.661140331593, 5673.943260567383, 6139.140520327158, 6174.536909731743, 6188.350223226541, 6242.406971337716, 6272.968635156795, 5266.552343050846, 6286.559940804145, 6365.649889256089, 6378.840528986812, 6379.872402551923, 5641.858953526113, 5730.627509211888, 5785.971070144622, 5869.2369991901205, 5870.11948207768, 6309.716062777186, 6323.30443651198, 6330.620162790247, 6332.018537126753, 6334.049892516067, 6129.31443852944, 6132.172156758869, 6132.172156758869, 6133.228064740242, 6169.876602467925, 6093.360197179321, 6100.762843515373, 6168.691565421711, 6253.0620406938415, 6311.495080393557, 6102.51954821894, 6187.3503282154725, 6213.937860621426, 6220.409061139185, 6223.813285601439, 6148.9692551536955, 6198.597091189058, 6242.656653883989, 6244.250689917239, 6270.717817698214, 4759.952400476021, 4820.590249828733, 4882.609391248691, 4895.951040489621, 4920.335754673145, 4177.788441351974, 4249.426327445817, 4373.037931655031, 4383.824647014084, 4384.386185933972, 5640.153976903497, 5665.5184309333345, 5673.404292549306, 5694.402087780723, 5739.655620662774, 6114.358196333954, 6139.938600613937, 6285.811425657238, 6293.37066293367, 6294.496440284766, 5602.467765562265, 5618.185363122325, 5640.661560306395, 5646.661200327993, 5657.943420565742, 5291.523762861284, 5294.179402192666, 5304.496072873071, 5307.787688492417, 5308.814191503356, 6253.749850006131, 6261.5616906816085, 6293.716782744787, 6301.212348456443, 6312.810615681537, 6239.251289845248, 6341.0, 6351.0, 6354.078658594385, 6362.904556431565, 6291.402316779899, 6316.05259211122, 6339.651319177489, 6339.778107766206, 6579.111819904443, 6000.0999850022845, 6040.214772079602, 6084.269887613515, 6133.723982420891, 6162.352952939877, 6153.692315384303, 6177.96911015442, 6240.188775459162, 6246.3441338660605, 6255.937440625536, 5200.713960732196, 6023.939760602338, 6036.939630603638, 6038.094285856981, 6045.788397406246, 5840.678762668094, 5883.823485295448, 5964.403559644001, 5997.850053748692, 6013.488853447506, 5872.794452194153, 5891.705414729332, 5903.734331955158, 5928.970355148197, 5935.465808077293, 5991.7902873399225, 6084.316360559349, 6092.939070609237, 6111.502681842977, 6131.601445906002, 4939.827106051271, 5771.365149833431, 5888.587798854039, 5891.852703682443, 5935.732892019783], "value": 5641.248072531825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.72569946636094, 513.6122227718074, 515.8014164546648, 519.5817367019549, 577.1832856508042, 30349.0, 41084.589154108486, 42560.14879702404, 42572.57427425725, 45008.77495612526, 43742.7812860936, 44074.77962610182, 44122.7793861031, 44134.0, 44226.778866105626, 8717.171868672465, 9649.565769540352, 9831.65589204378, 9845.55694993727, 9846.950765246163, 8844.513551754651, 10370.533326000348, 11096.944515277453, 11537.250078744897, 11718.648440546796, 11730.0, 11823.408829558532, 11823.822642660323, 11836.112291578123, 11839.052875769961, 8752.47485150893, 11606.0, 11712.004479619158, 11727.237729547594, 11843.348615826084, 11680.41597920109, 11772.882271177265, 11782.705432364173, 11783.175177737561, 11794.52821887132, 40037.39943900844, 40480.0, 40523.0, 40545.0, 40563.39154912678, 2580.895986602071, 3220.0983724556927, 3717.683996860243, 3869.6323849234277, 3950.604939506055, 28330.163408780765, 28349.85825070882, 28682.426351473063, 28688.282792930135, 28861.56707649387, 2704.2157774245447, 2718.660167479065, 2725.645666063399, 2742.3829638331417, 2811.4096039831456, 481233.0, 482112.0, 482278.0, 482361.0, 482461.0, 58829.70585147047, 59234.70382648059, 59662.70168649216, 59807.0, 59835.700821495615, 35575.509714320266, 36371.63628363683, 62185.689071554356, 62394.688026560485, 62414.375856241764, 16838.410655626994, 16913.985160890385, 16959.830401696072, 16962.0, 17014.234359453607, 11767.293962362286, 11936.10479214045, 11952.641420757218, 12169.391530423443, 12922.41849116792, 17263.913680431768, 19383.806161938483, 19500.1069848681, 20040.799592004187, 20042.89978550098, 8170.550619715973, 8179.631916563769, 8186.508809471451, 8216.917830821616, 8238.546879921541, 937.4796987671771, 1009.3893194617298, 1043.7181960870523, 1073.2111897755144, 1078.8219943709357, 29507.409851802844, 29795.851020744758, 30103.548446773315, 30385.392292154032, 30469.0, 1065.27957348881, 1068.8129577323937, 1094.8084085285043, 1150.3270586706703, 1203.8615559210627, 15467.5359739208, 15472.690546189238, 15516.0, 15684.843151568566, 15694.843051569567, 668140.0, 673383.0, 673458.0, 675025.0, 677997.0, 58862.41137588655, 60744.696276518334, 60785.392146077975, 60873.0, 60924.69537652283, 1201.9158658893969, 1320.18148747777, 1367.3641756583038, 1462.4808193091299, 1496.8203815542204, 29987.355753131837, 30291.545626816056, 30587.54118688177, 30886.073417797503, 31506.842465787526, 59129.0, 59998.70000649969, 60006.0, 60098.39901600928, 60125.699371502866, 588387.0, 609737.0, 611042.0, 611506.0, 612023.0, 33321.83339083289, 33581.83209083939, 35888.641113589576, 35937.28125437529, 36925.97183435104, 43655.56344436515, 46191.0, 46484.53515464938, 46926.76536617295, 47020.0, 37437.81281093577, 37546.0, 37992.810035949646, 38798.0, 39090.80454597709, 31654.0, 36824.26351473009, 37894.81052594719, 38546.6145338543, 38648.033799155244, 37216.0, 37710.62289377071, 38165.04587385338, 38571.2285754289, 38897.027574310865, 32417.83791081124, 32530.02409927706, 32747.34505309928, 32800.83599581987, 33073.669263308024, 20863.582728345045, 24556.75443245616, 24612.753872461046, 25154.874225628755, 25290.241292761246], "value": 20538.561199895277, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.4706410979695, 360.0817914318488, 374.27951193951634, 387.11544121681965, 401.86336645540547, 20398.490037749045, 22508.212212572562, 22594.426255689526, 22606.0, 22890.313290601287, 21066.84132372719, 21351.57296854062, 22081.779182208174, 22635.547289054208, 22731.090756369766, 5819.563532735051, 6013.849653758647, 6167.414095660905, 6175.475084617813, 6223.844403889916, 4261.552536983619, 4476.2390393632995, 6032.728527216286, 6413.486921046305, 6794.796156115324, 5973.820785376446, 6353.142325786029, 6742.2246441659345, 6761.0196521504595, 6784.559003664771, 6516.478681705452, 6694.698738556776, 6720.865582688368, 6727.8654426911435, 6771.72913083476, 5251.816186433494, 5589.965856316594, 5607.775688972436, 5838.095095260199, 5865.266841644794, 13745.450181992708, 13972.86027139736, 14025.368858401396, 18584.90707546467, 26677.599836002475, 2095.8428117891067, 2221.944451388712, 2225.3419119288496, 2254.9549009019893, 2867.225849020753, 9632.373895696757, 10124.98750124989, 10235.87405385407, 12231.837975392324, 14749.852501474956, 1368.589423173044, 1718.8710846686424, 1722.9310827566883, 1759.7888253409544, 1974.299123811055, 113996.29005564924, 119189.0, 122291.99999999911, 122697.0, 124654.37672811668, 10701.036906678437, 11152.275102118336, 11702.88297117035, 11745.412729363497, 11925.88074119265, 15118.773218401733, 15253.542393728207, 15263.771043434357, 15269.923650381677, 15284.38862445512, 7905.56519391439, 8537.317014638815, 8794.91205087941, 8984.8652270216, 9096.135867092748, 8900.884690358453, 8903.287736981027, 8903.866442003375, 9026.413283136706, 9058.728238152866, 3870.96129038706, 4612.584867361954, 4693.507181745936, 4752.643551733634, 4762.52374762521, 1795.2280519376532, 1814.4338012092976, 1839.5099969025132, 1965.4791480257743, 2007.5884443689008, 888.2671795768517, 953.9427634341962, 972.2513664478454, 1006.7479860085788, 1018.6791160784329, 20272.695909561367, 20356.592868142554, 21329.78670213309, 21607.67588486174, 21633.72398110916, 923.886716506608, 930.4370855632245, 978.9167920726676, 980.578351308944, 1064.9041586257274, 10849.403282819363, 11257.380844053492, 11520.711982200344, 11558.711032224093, 11593.0, 596259.0, 598990.0555469492, 600195.0, 618980.0, 619966.9001655054, 132187.0, 134377.0, 135052.64947350597, 135490.0, 136465.317673411, 1014.8782146142363, 1037.9377237365782, 1056.3186744549723, 1064.1858977881927, 1066.3655125200494, 17830.91084544569, 18359.908200459446, 18566.700330976702, 18976.956267405425, 19359.22563097517, 44643.88390290139, 49652.83792009478, 51170.0, 54954.0, 55566.44433555612, 80578.98552536, 81576.59211704141, 81801.0, 81987.5900620493, 82726.17273827185, 8224.588770561568, 8351.540665263468, 8359.832803343777, 8485.066642669424, 8670.462887640959, 9449.433034017706, 12563.18339307942, 12976.805347919977, 13366.599002029954, 13396.732065358443, 10970.835437468284, 11334.376609286239, 11655.825162622397, 11716.06271498261, 11754.23597466162, 9585.424874507551, 11025.669229922794, 11252.130706669746, 11422.371769552423, 11615.186936914673, 8283.37874659391, 11671.766564668489, 11777.528898844294, 11862.347570883685, 11922.403879806148, 6874.312568743086, 7590.582517961565, 7598.0502437195355, 8481.745547633582, 8680.262177714965, 9528.666496672584, 11530.827037594276, 11556.94221528887, 11601.709957251136, 11767.52929882795], "value": 9694.8729003501, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1461.8830493560515, 1629.6985057764314, 1658.8424099710528, 1927.5470264487842, 2015.5192013047988, 8178.345732341413, 8851.822963540726, 9126.589303481343, 9138.314626403013, 9166.220871225938, 8670.95664521678, 8981.461112333265, 8982.910170898289, 9077.818443631124, 9078.682246121387, 2405.711314642241, 2460.015993602555, 2461.507698460306, 2462.445949661321, 2463.815213858959, 2387.8686672233107, 2453.754624537541, 2457.0785955266774, 2457.6313552967017, 2463.3964678653756, 2015.9496012599657, 2407.5786737320987, 2407.9759202408013, 2452.4849781545895, 2452.6934133233344, 2388.319328991238, 2416.2147302126878, 2427.017058091476, 2430.9270721878283, 2461.7415171406924, 2372.3950392649936, 2441.926742197737, 2469.5307891500556, 2469.938251543708, 2470.5676506611453, 8095.716649917284, 8370.748877533684, 9010.14403631654, 9086.045965173627, 9099.590518426621, 5344.171653393731, 5564.693941833198, 5564.944350556483, 5595.38450770415, 5600.52395546379, 3510.508528805969, 3665.0, 3709.2025214578994, 3715.108373990223, 3743.382341913581, 1040.47976011994, 1053.5891002508952, 1066.1044722433116, 1067.8932106789337, 1072.1476426241156, 12052.337121458317, 12300.138990270685, 12303.630891073282, 12321.075919306088, 12330.136890417674, 4919.557239848431, 4956.330895329084, 5048.343715316985, 5062.417821950449, 5091.312672789126, 10617.309874858109, 10661.093807026335, 10678.0, 10686.14510839131, 10695.037446629685, 5645.887082258332, 5649.067903795828, 5652.293463317085, 5655.406182350794, 5656.943430565724, 4950.133726597831, 5032.647714660012, 5062.670926389773, 5088.982203559303, 5131.384233891882, 3952.26883026641, 4012.759234445884, 4062.2687916175373, 4125.628693417607, 4137.46212992309, 1300.5318085489216, 1306.7255876265901, 1315.3620494060374, 1336.1381908669048, 1340.537514557476, 798.3054742374171, 836.5190015740973, 894.3113802372137, 929.8977112517617, 930.9813803723888, 5692.14617807324, 5922.082077278029, 6069.453749162597, 6080.331163572004, 6095.299040610298, 452.5723191583987, 467.6235630317559, 479.30740080583917, 492.24194740099847, 494.75262368815595, 3394.9151271218416, 3813.56144043433, 3982.681385489154, 4039.6364327210104, 4088.12105397341, 11026.0, 11344.376059316817, 11351.716207094889, 11352.94323528377, 11378.203525753283, 10837.83743243852, 11241.83137252942, 11251.212415130862, 11259.211855170222, 11304.0, 8379.162083791573, 8489.235968762841, 8548.401611887233, 8581.227689507974, 8623.223909848144, 5163.638545301793, 5978.491828194652, 6017.699115044318, 6125.081237814364, 6146.955017646921, 10063.295569310078, 10172.949135254275, 10524.947375263075, 10539.78920421572, 10613.150947924301, 8537.658493660432, 10316.226283028953, 10402.063814256753, 10623.256372053882, 10722.571097156026, 7547.886781698388, 7832.960835195788, 7840.803979900548, 7845.568493732671, 7854.136045035156, 7108.898120791286, 8430.536320502431, 8600.483970961763, 8607.18231767981, 8608.741737747878, 7724.382049435919, 7806.414518911218, 7819.296263336327, 7854.842903142019, 7976.322012628992, 7494.138174110047, 7645.426593005435, 7669.693212271447, 7722.575258360844, 7773.611319434119, 6955.269696681978, 7237.819054523468, 7296.233895441114, 7306.050213472215, 7314.634268286671, 3921.627445392683, 5035.043341765053, 5055.443901170795, 5103.10695628271, 5183.222516622537, 4210.926213815457, 5057.924131137962, 5130.97434512825, 5134.358205224347, 5345.759440825094], "value": 4634.879934834721, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.93545549068216, 306.25274330633255, 315.27486780405064, 324.14749209578787, 325.66293885828173, 4595.839145629903, 5277.577793776498, 5325.973370133149, 5336.4129945705945, 5355.919661205085, 4586.908261834761, 4680.923387620849, 5108.773894265368, 5246.580273578114, 5311.787528498855, 3144.2453811085347, 3543.875964341236, 3843.2121415109973, 4049.9190016199805, 4053.3109371406786, 3969.801509924492, 3982.0443093657605, 4015.638592526672, 4026.5168179818315, 4038.8788336349958, 3474.724055743492, 3856.7465573688523, 3964.8612298569556, 3976.7216294859372, 3998.920021599566, 3285.622153452348, 3771.6416940390754, 3895.162540053902, 3998.820053097618, 4015.859444919443, 3487.1169568433015, 3504.4918486819524, 3504.842282097312, 3515.490253913168, 3531.4173161428334, 4003.5195776506716, 4730.0370346306245, 5038.0, 5042.966191930645, 5064.898702025976, 3721.8697345592773, 4166.625003749647, 4182.079942412664, 4525.389072475206, 4537.0, 5457.345118585756, 5467.535259502947, 5524.066576698124, 5528.474794894479, 5536.335639723218, 3519.348920449725, 3781.0, 3797.259534390795, 3806.885793426202, 3817.2174704185577, 5546.889062218774, 5701.315842098892, 5770.336411312711, 5771.797987070432, 5788.507976821975, 3557.5375201223674, 3798.639129282714, 3825.2977425046142, 3848.0, 3862.594427585063, 3782.678472329883, 3899.3038028457713, 3913.8434462621176, 3919.2749341371937, 3939.783311917815, 3953.367461206193, 4339.284018136972, 4388.517263101056, 4500.099980003947, 4526.0, 4398.75806830621, 4466.910661786811, 4904.435989861212, 5549.250851135126, 5551.417101204315, 1295.5077070713069, 1319.4392383237087, 1326.4083100279704, 1331.3276795218546, 1338.3605083772302, 773.8258891749311, 798.5448294472211, 820.24127681894, 825.5542007316102, 831.9459235149695, 4390.912181756347, 4943.530364615356, 5007.474215207423, 5087.465816089258, 5107.387113546398, 4823.782929768169, 5432.67403955756, 5662.886742265132, 5664.291963504562, 5742.253507044057, 4836.274558816206, 5101.617378696613, 5114.974425127925, 5120.667156634807, 5129.9743501282255, 2509.3224829296355, 2923.9122826315242, 2948.6461624605186, 2949.4810172761045, 2969.658489273761, 4978.440415103219, 5571.272905399258, 5722.885542289214, 5735.139729040678, 5895.7052147393315, 4415.7856589437515, 4957.702537847741, 5119.667221630582, 5788.363280039192, 5826.41735826414, 5187.766550505161, 5286.788528458818, 5627.582347856913, 5739.71301434935, 5903.439173278532, 5147.639665223548, 5586.972065139648, 5606.981486664716, 5613.354464236665, 5643.830685079454, 5051.520105589963, 5348.57211423093, 5483.698396588226, 5503.917441238465, 5554.888902221852, 5330.573554115739, 5802.796902108406, 5809.738561764646, 5824.271966004249, 5854.677992710442, 3138.2154461384653, 3610.0586953535267, 3643.198496330803, 3784.905377365588, 3803.8098095094583, 4602.608778253886, 4949.505049495022, 5605.411431799603, 5605.831825045255, 5705.201271822031, 4857.417109946829, 5437.728113594384, 5445.264889239902, 5477.548449660922, 5485.915352166151, 4876.024795040935, 4900.362952816111, 5371.301730774974, 5488.231647569263, 5489.3687225969525, 4712.410948631421, 5113.823820521376, 5421.7560209791445, 5451.945480545144, 5469.0, 4591.908161836812, 4863.990090207092, 5127.5897928166405, 5258.631895767413, 5543.77824887, 4795.616350692006, 4994.101061808909, 5403.756830942696, 5406.621536492407, 5476.835694929158], "value": 3978.4918027452995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.3414663383497, 724.4494184419839, 752.5935994562934, 753.077480086894, 753.9924600753994, 14991.700165996674, 15140.394384224617, 15661.921690391531, 15682.529524114281, 15916.681666366694, 14519.49181778638, 14566.344514496846, 14572.125672459633, 14611.342489587967, 14623.268836558183, 739.345679074018, 934.2152591822851, 943.2406912435487, 953.4422362917702, 953.5756588318201, 753.9472236943416, 908.318760929303, 931.1154403316842, 931.7065124485765, 932.7668082979255, 982.7887004294075, 987.856760769688, 989.9851502227436, 990.6384169778015, 992.007992007992, 770.5915864591719, 790.5059337913804, 982.1700662939804, 982.4400091947592, 984.517586382671, 593.3710267116827, 593.4540222994855, 594.2898236607256, 718.6263143165525, 735.3675838778646, 14961.326740296712, 15002.924985375112, 15016.023958442665, 15016.849831501655, 15030.624234394227, 12178.695532611668, 12382.257064576153, 12425.068119891044, 12507.93746031264, 12712.3008234548, 10000.299979001473, 10357.016083472134, 10495.317804342769, 10520.684379468628, 10717.0, 4021.7586944783407, 4108.876733697994, 4215.515215750197, 4238.745675259494, 4330.328799036154, 84705.57647211786, 88044.23911521798, 88944.55527722384, 89157.0, 89540.55229723876, 7328.853422931512, 9409.388389754644, 9420.0, 9642.951785241028, 9966.754155730534, 20680.069396877177, 20783.792162078487, 20795.896020520104, 20837.0, 20867.0, 7145.499815013003, 7274.345308922222, 7442.813929651802, 7509.399248060142, 7546.396288296923, 3147.3233254850315, 3935.2129574085297, 3943.743656662308, 3962.5046869141356, 3978.0850404407147, 3225.4033003894356, 3453.309338132384, 3483.3381657484997, 3577.5349204603754, 3583.5341405617623, 629.9559030867887, 631.782035197856, 652.1522021372292, 652.7584793626388, 655.8786624474487, 500.83555732921104, 514.9819756308511, 610.3957082488394, 619.3342157180958, 628.2398298059308, 6892.517523773287, 7413.703451861973, 7865.134835168127, 8008.439409241297, 8110.716124935599, 567.0841590830779, 572.9197912292283, 586.3420856178121, 605.1014243847883, 614.6895817612077, 3732.5147730794824, 3961.504811898513, 3970.80145992705, 4049.149678567475, 4100.446439730598, 92820.0, 94435.0, 98645.0, 99995.0, 100096.99903000877, 43793.78103109464, 69788.65105674439, 75798.0, 82598.1090661824, 85565.0, 78201.0, 78261.82607260895, 78631.8205226922, 78735.60632196802, 78747.0, 6682.365175308338, 7750.224977502205, 7799.259070388304, 7883.921160788319, 8151.805212361697, 47206.527934720216, 47383.0, 47754.0, 47925.520744792106, 48703.5129648699, 49798.0, 65733.35666608372, 66223.33776662171, 68416.0, 69018.65490672716, 10227.335223210468, 10244.692659220234, 10435.739106522398, 10582.4708764563, 10666.946665266623, 10704.411257380918, 11040.619922509686, 11228.66314010581, 11600.5359785611, 11657.18399712012, 10663.13050042722, 10735.838962415726, 10778.461076945965, 10839.13286937059, 10950.890491094988, 9368.578413971523, 10457.95420457948, 10529.36823790575, 10607.0, 10764.461776910966, 10596.258261921592, 10602.310849794738, 10738.140948723927, 10892.346459212473, 11541.480633371686, 8843.867341990004, 8944.195022448012, 9060.456372617664, 9234.399764015317, 9375.0, 8923.196912277926, 8951.0, 8988.280937525113, 8994.82010359776, 9085.227755640844], "value": 6811.747175491991, "unit": "score"}], "name": "dromaeo_css", "value": 7215.3687925675995, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 112.0, 113.0, 111.0, 113.0, 112.0, 112.0, 112.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 118.0, 143.0, 200.0, 119.0, 144.0, 139.0, 121.0, 174.0, 119.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 188.0, 186.0, 187.0, 185.0, 187.0, 184.0, 184.0, 184.0, 229.0], "value": 190.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 57.0, 126.0, 56.0, 63.0, 57.0, 67.0, 134.0, 78.0], "value": 79.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 132.0, 80.0, 82.0, 77.0, 100.0, 80.0, 78.0, 101.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 87.0, 85.0, 89.0, 87.0, 89.0, 85.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 92.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 139.0, 98.0, 86.0, 84.0, 86.0, 84.0, 87.0, 84.0, 86.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 56.0, 56.0, 57.0, 56.0, 120.0, 57.0, 55.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 79.0, 73.0, 71.0, 68.0, 68.0, 69.0, 69.0, 70.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 92.0, 103.0, 106.0, 105.0, 82.0, 85.0, 86.0, 85.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 136.0, 138.0, 138.0, 137.0, 137.0, 135.0, 137.0, 217.0, 173.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 165.0, 165.0, 164.0, 164.0, 165.0, 165.0, 225.0, 190.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 75.0, 84.0, 88.0, 85.0, 66.0, 65.0, 66.0, 64.0], "value": 72.6, "unit": "score"}], "name": "kraken", "value": 1514.2, "alertThreshold": 2.0}]} 15:14:49 INFO - Return code: 0 15:14:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:14:49 ERROR - # TBPL SUCCESS # 15:14:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:14:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:14:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:14:49 INFO - 2016-05-13 15:14:49.099 screenresolution[1950:12744] starting screenresolution argv=screenresolution get 15:14:49 INFO - 2016-05-13 15:14:49.102 screenresolution[1950:12744] Display 0: 1600x1200x32@60 15:14:49 INFO - 2016-05-13 15:14:49.108 screenresolution[1951:12747] starting screenresolution argv=screenresolution list 15:14:49 INFO - Available Modes on Display 0 15:14:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:14:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:14:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:14:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:14:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:14:49 INFO - Intel Iris: 15:14:49 INFO - Chipset Model: Intel Iris 15:14:49 INFO - Type: GPU 15:14:49 INFO - Bus: Built-In 15:14:49 INFO - VRAM (Dynamic, Max): 1536 MB 15:14:49 INFO - Vendor: Intel (0x8086) 15:14:49 INFO - Device ID: 0x0a2e 15:14:49 INFO - Revision ID: 0x0009 15:14:49 INFO - Displays: 15:14:49 INFO - Display: 15:14:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:14:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:14:49 INFO - Display Serial Number: 1600x1200 60 15:14:49 INFO - Main Display: Yes 15:14:49 INFO - Mirror: Off 15:14:49 INFO - Online: Yes 15:14:49 INFO - Rotation: Supported 15:14:49 INFO - Return code: 0 15:14:49 INFO - Running post-action listener: _resource_record_post_action 15:14:49 INFO - ##### 15:14:49 INFO - ##### Finished run-tests step (success) 15:14:49 INFO - ##### 15:14:49 INFO - Running post-run listener: _resource_record_post_run 15:14:49 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1126400; Write bytes: 692299264; Read time: 16; Write time: 1773 15:14:49 INFO - TinderboxPrint: CPU usage
54.0% 15:14:49 INFO - TinderboxPrint: I/O read bytes / time
1,126,400 / 16 15:14:49 INFO - TinderboxPrint: I/O write bytes / time
692,299,264 / 1,773 15:14:49 INFO - TinderboxPrint: CPU idle
2,233.0 (46.0%) 15:14:49 INFO - TinderboxPrint: CPU system
976.5 (20.1%) 15:14:49 INFO - TinderboxPrint: CPU user
1,640.2 (33.8%) 15:14:49 INFO - TinderboxPrint: Swap in / out
396,218,368 / 0 15:14:49 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 386474496; Write bytes: 381011968; Read time: 29020; Write time: 704 15:14:49 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1126400; Write bytes: 293932544; Read time: 16; Write time: 1027 15:14:49 INFO - Running post-run listener: _upload_blobber_files 15:14:49 INFO - Blob upload gear active. 15:14:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:14:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:14:49 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'] 15:14:49 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 15:14:50 INFO - (blobuploader) - INFO - Open directory for files ... 15:14:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:14:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:14:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:51 INFO - (blobuploader) - INFO - Done attempting. 15:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:14:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:14:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:51 INFO - (blobuploader) - INFO - Done attempting. 15:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:14:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:14:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:54 INFO - (blobuploader) - INFO - Done attempting. 15:14:54 INFO - (blobuploader) - INFO - Iteration through files over. 15:14:54 INFO - Return code: 0 15:14:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:14:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:14:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e6d1c09c1f991c0e812c30e51e992405ab37c32f908b6602f5e97864bd80b0363814e55628c5f61e10a4864d401cf0b03a9cbf68baf1e725c9c5f324b53e779", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83eae211e6507cb13179952ba748d3cad610ac4f57e29e033b8d294c6fcb5795c0f45798283d9f46cf3a20510ec5ad5ca0b469d13509ca3bafa1dcc4b4198fb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8ad7cbd629391772d23b45c366293573976b0ba424f77ca0e3d28867f64a89add91b474eddd14664dfde893fb99ae7ab228a1ac6a379f597d778d8d8faaafbd"} 15:14:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:14:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:14:54 INFO - Contents: 15:14:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e6d1c09c1f991c0e812c30e51e992405ab37c32f908b6602f5e97864bd80b0363814e55628c5f61e10a4864d401cf0b03a9cbf68baf1e725c9c5f324b53e779", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83eae211e6507cb13179952ba748d3cad610ac4f57e29e033b8d294c6fcb5795c0f45798283d9f46cf3a20510ec5ad5ca0b469d13509ca3bafa1dcc4b4198fb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8ad7cbd629391772d23b45c366293573976b0ba424f77ca0e3d28867f64a89add91b474eddd14664dfde893fb99ae7ab228a1ac6a379f597d778d8d8faaafbd"} 15:14:54 INFO - Running post-run listener: copy_logs_to_upload_dir 15:14:54 INFO - Copying logs to upload dir... 15:14:54 INFO - mkdir: /builds/slave/test/build/upload/logs 15:14:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.926717 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-05-13 15:14:54.489541) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 15:14:54.492852) ========= 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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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/8e6d1c09c1f991c0e812c30e51e992405ab37c32f908b6602f5e97864bd80b0363814e55628c5f61e10a4864d401cf0b03a9cbf68baf1e725c9c5f324b53e779", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83eae211e6507cb13179952ba748d3cad610ac4f57e29e033b8d294c6fcb5795c0f45798283d9f46cf3a20510ec5ad5ca0b469d13509ca3bafa1dcc4b4198fb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8ad7cbd629391772d23b45c366293573976b0ba424f77ca0e3d28867f64a89add91b474eddd14664dfde893fb99ae7ab228a1ac6a379f597d778d8d8faaafbd"} build_url:https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010736 build_url: 'https://queue.taskcluster.net/v1/task/FKsZbi2aTm-KKNEhFZH3KA/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/8e6d1c09c1f991c0e812c30e51e992405ab37c32f908b6602f5e97864bd80b0363814e55628c5f61e10a4864d401cf0b03a9cbf68baf1e725c9c5f324b53e779", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83eae211e6507cb13179952ba748d3cad610ac4f57e29e033b8d294c6fcb5795c0f45798283d9f46cf3a20510ec5ad5ca0b469d13509ca3bafa1dcc4b4198fb6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f8ad7cbd629391772d23b45c366293573976b0ba424f77ca0e3d28867f64a89add91b474eddd14664dfde893fb99ae7ab228a1ac6a379f597d778d8d8faaafbd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 15:14:54.523122) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 15:14:54.523626) ========= 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.eIj4RUTPwO/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.wh9bE5rBcr/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.005324 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 15:14:54.623733) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 15:14:54.624094) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 15:14:54.624598) ========= ========= Total master_lag: 0.27 =========