builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0169 starttime: 1460045845.06 results: success (0) buildid: 20160407090711 builduid: b4bf92f8cf0d4c56bd80a09e54bdb91e revision: 55f0de142be46043246b18108299aebf2bd95a64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.062494) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.062918) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.063206) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R3FckehF7H/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.htDqw2przm/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 /builds/slave/test program finished with exit code 0 elapsedTime=0.005594 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.085405) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.085698) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.102797) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.103648) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R3FckehF7H/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.htDqw2przm/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.005362 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.151507) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.151804) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.152197) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.152481) ========= 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.R3FckehF7H/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.htDqw2przm/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-04-07 09:17:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 48.9M=0s 2016-04-07 09:17:25 (48.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124025 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.293312) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.293721) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R3FckehF7H/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.htDqw2przm/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.020373 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:25.343056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-07 09:17:25.343425) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 55f0de142be46043246b18108299aebf2bd95a64 --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 55f0de142be46043246b18108299aebf2bd95a64 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R3FckehF7H/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.htDqw2przm/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-04-07 09:17:25,423 truncating revision to first 12 chars 2016-04-07 09:17:25,423 Setting DEBUG logging. 2016-04-07 09:17:25,423 attempt 1/10 2016-04-07 09:17:25,423 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/55f0de142be4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-07 09:17:27,112 unpacking tar archive at: fx-team-55f0de142be4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.940109 ========= master_lag: 2.67 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-07 09:17:29.950658) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:29.953991) ========= echo 55f0de142be46043246b18108299aebf2bd95a64 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'55f0de142be46043246b18108299aebf2bd95a64'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R3FckehF7H/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.htDqw2przm/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 55f0de142be46043246b18108299aebf2bd95a64 program finished with exit code 0 elapsedTime=0.005224 script_repo_revision: '55f0de142be46043246b18108299aebf2bd95a64' ========= master_lag: 0.15 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:30.110114) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:30.110384) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:30.124906) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-07 09:17:30.125186) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.R3FckehF7H/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.htDqw2przm/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:17:30 INFO - MultiFileLogger online at 20160407 09:17:30 in /builds/slave/test 09:17:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:17:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:30 INFO - {'append_to_log': False, 09:17:30 INFO - 'base_work_dir': '/builds/slave/test', 09:17:30 INFO - 'blob_upload_branch': 'Fx-Team', 09:17:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:30 INFO - 'branch': 'Fx-Team', 09:17:30 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:30 INFO - 'config_files': ('talos/mac_config.py',), 09:17:30 INFO - 'default_actions': ('clobber', 09:17:30 INFO - 'read-buildbot-config', 09:17:30 INFO - 'download-and-extract', 09:17:30 INFO - 'populate-webroot', 09:17:30 INFO - 'create-virtualenv', 09:17:30 INFO - 'install', 09:17:30 INFO - 'run-tests'), 09:17:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:30 INFO - 'download_minidump_stackwalk': True, 09:17:30 INFO - 'download_symbols': 'ondemand', 09:17:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:30 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:30 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:30 INFO - 'installer_path': 'installer.exe', 09:17:30 INFO - 'log_level': 'info', 09:17:30 INFO - 'log_name': 'talos', 09:17:30 INFO - 'log_to_console': True, 09:17:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:17:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:17:30 INFO - 'opt_config_files': (), 09:17:30 INFO - 'pip_index': False, 09:17:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:30 INFO - 'cmd': ('bash', 09:17:30 INFO - '-c', 09:17:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:30 INFO - 'enabled': True, 09:17:30 INFO - 'halt_on_failure': False, 09:17:30 INFO - 'name': 'check_screen_resolution'},), 09:17:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:30 INFO - 'cmd': ('bash', 09:17:30 INFO - '-c', 09:17:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:30 INFO - 'enabled': True, 09:17:30 INFO - 'halt_on_failure': False, 09:17:30 INFO - 'name': 'check_screen_resolution'},), 09:17:30 INFO - 'run_cmd_checks_enabled': True, 09:17:30 INFO - 'sps_profile': False, 09:17:30 INFO - 'sps_profile_interval': 0, 09:17:30 INFO - 'suite': 'dromaeojs-e10s', 09:17:30 INFO - 'system_bits': '32', 09:17:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:30 INFO - 'title': 't-yosemite-r7-0169', 09:17:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:30 INFO - 'use_talos_json': True, 09:17:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:30 INFO - 'work_dir': 'build'} 09:17:30 INFO - ##### 09:17:30 INFO - ##### Running clobber step. 09:17:30 INFO - ##### 09:17:30 INFO - Running pre-action listener: _resource_record_pre_action 09:17:30 INFO - Running main action method: clobber 09:17:30 INFO - rmtree: /builds/slave/test/build 09:17:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:31 INFO - Running post-action listener: _resource_record_post_action 09:17:31 INFO - ##### 09:17:31 INFO - ##### Running read-buildbot-config step. 09:17:31 INFO - ##### 09:17:31 INFO - Running pre-action listener: _resource_record_pre_action 09:17:31 INFO - Running main action method: read_buildbot_config 09:17:31 INFO - Using buildbot properties: 09:17:31 INFO - { 09:17:31 INFO - "project": "", 09:17:31 INFO - "product": "firefox", 09:17:31 INFO - "script_repo_revision": "production", 09:17:31 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:17:31 INFO - "repository": "", 09:17:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:17:31 INFO - "buildid": "20160407090711", 09:17:31 INFO - "stage_platform": "macosx64", 09:17:31 INFO - "basedir": "/builds/slave/test", 09:17:31 INFO - "buildnumber": 590, 09:17:31 INFO - "platform": "yosemite_r7", 09:17:31 INFO - "mozci_request": { 09:17:31 INFO - "priority": -1, 09:17:31 INFO - "type": "trigger_all_talos_jobs", 09:17:31 INFO - "times": 6 09:17:31 INFO - }, 09:17:31 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:17:31 INFO - "slavebuilddir": "test", 09:17:31 INFO - "branch": "fx-team", 09:17:31 INFO - "revision": "55f0de142be46043246b18108299aebf2bd95a64", 09:17:31 INFO - "slavename": "t-yosemite-r7-0169", 09:17:31 INFO - "builduid": "b4bf92f8cf0d4c56bd80a09e54bdb91e", 09:17:31 INFO - "repo_path": "integration/fx-team" 09:17:31 INFO - } 09:17:31 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg. 09:17:31 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.test_packages.json. 09:17:31 INFO - Running post-action listener: _resource_record_post_action 09:17:31 INFO - ##### 09:17:31 INFO - ##### Running download-and-extract step. 09:17:31 INFO - ##### 09:17:31 INFO - Running pre-action listener: _resource_record_pre_action 09:17:31 INFO - Running main action method: download_and_extract 09:17:31 INFO - mkdir: /builds/slave/test/build/tests 09:17: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')]} 09:17:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.test_packages.json 09:17:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:17:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 09:17:31 INFO - Downloaded 1199 bytes. 09:17:31 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:17:31 INFO - Using the following test package requirements: 09:17:31 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:17:31 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:31 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:17:31 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:31 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:17:31 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:31 INFO - u'jsshell-mac.zip'], 09:17:31 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:31 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:17:31 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:17:31 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:31 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:17:31 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:31 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:17:31 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:31 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:17:31 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:31 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:17:31 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:17: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')]} 09:17:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.common.tests.zip 09:17:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:17:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:17:31 INFO - Downloaded 17970719 bytes. 09:17:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:17:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:17:34 INFO - Return code: 0 09:17:34 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:17: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')]} 09:17:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.common.tests.zip 09:17:34 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:17:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:17:35 INFO - Downloaded 17970719 bytes. 09:17:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:17:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:17:38 INFO - Return code: 0 09:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.talos.tests.zip 09:17:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:17:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:17:39 INFO - Downloaded 10847617 bytes. 09:17:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:17:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:17:39 INFO - Return code: 0 09:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg 09:17:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 09:17:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 09:17:45 INFO - Downloaded 108918781 bytes. 09:17:45 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg 09:17:45 INFO - mkdir: /builds/slave/test/properties 09:17:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:45 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:45 INFO - Contents: 09:17:45 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg 09:17:45 INFO - Running post-action listener: _resource_record_post_action 09:17:45 INFO - Running post-action listener: set_extra_try_arguments 09:17:45 INFO - ##### 09:17:45 INFO - ##### Running populate-webroot step. 09:17:45 INFO - ##### 09:17:45 INFO - Running pre-action listener: _resource_record_pre_action 09:17:45 INFO - Running main action method: populate_webroot 09:17:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:45 INFO - '--tppagecycles', 09:17:45 INFO - '7'], 09:17:45 INFO - 'tests': ['tsvgm']}}, 09:17:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:45 INFO - 'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:45 INFO - 'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['damp', 'tps']}, 09:17:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:45 INFO - 'tests': ['damp', 'tps']}, 09:17:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['dromaeo_dom']}, 09:17:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['a11yr', 09:17:45 INFO - 'ts_paint', 09:17:45 INFO - 'tpaint', 09:17:45 INFO - 'sessionrestore', 09:17:45 INFO - 'sessionrestore_no_auto_restore', 09:17:45 INFO - 'tabpaint']}, 09:17:45 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:45 INFO - 'ts_paint', 09:17:45 INFO - 'tpaint', 09:17:45 INFO - 'sessionrestore', 09:17:45 INFO - 'sessionrestore_no_auto_restore', 09:17:45 INFO - 'tabpaint']}, 09:17:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:45 INFO - 'ts_paint', 09:17:45 INFO - 'tpaint', 09:17:45 INFO - 'sessionrestore', 09:17:45 INFO - 'sessionrestore_no_auto_restore', 09:17:45 INFO - 'tabpaint']}, 09:17:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:45 INFO - 'ts_paint', 09:17:45 INFO - 'tpaint', 09:17:45 INFO - 'sessionrestore', 09:17:45 INFO - 'sessionrestore_no_auto_restore', 09:17:45 INFO - 'tabpaint']}, 09:17:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['a11yr', 09:17:45 INFO - 'ts_paint', 09:17:45 INFO - 'tpaint', 09:17:45 INFO - 'sessionrestore', 09:17:45 INFO - 'sessionrestore_no_auto_restore', 09:17:45 INFO - 'tabpaint']}, 09:17:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['a11yr', 09:17:45 INFO - 'ts_paint', 09:17:45 INFO - 'tpaint', 09:17:45 INFO - 'sessionrestore', 09:17:45 INFO - 'sessionrestore_no_auto_restore', 09:17:45 INFO - 'tabpaint']}, 09:17:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['tsvgx', 09:17:45 INFO - 'tsvgr_opacity', 09:17:45 INFO - 'tart', 09:17:45 INFO - 'tscrollx', 09:17:45 INFO - 'cart']}, 09:17:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:45 INFO - 'tsvgr_opacity', 09:17:45 INFO - 'tart', 09:17:45 INFO - 'tscrollx', 09:17:45 INFO - 'cart']}, 09:17:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:45 INFO - 'talos_options': ['--disable-e10s'], 09:17:45 INFO - 'tests': ['tp5o']}, 09:17:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:45 INFO - 'tests': ['tp5o']}, 09:17:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:45 INFO - 'talos_options': ['--disable-e10s', 09:17:45 INFO - '--xperf_path', 09:17:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:45 INFO - 'tests': ['tp5n']}, 09:17:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:45 INFO - 'talos_options': ['--xperf_path', 09:17:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:45 INFO - 'tests': ['tp5n']}}, 09:17:45 INFO - 'talos.zip': {'path': '', 09:17:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:45 INFO - Running post-action listener: _resource_record_post_action 09:17:45 INFO - ##### 09:17:45 INFO - ##### Running create-virtualenv step. 09:17:45 INFO - ##### 09:17:45 INFO - Running pre-action listener: _resource_record_pre_action 09:17:45 INFO - Running main action method: create_virtualenv 09:17:45 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:45 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 09:17:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:45 INFO - Using real prefix '/tools/python27' 09:17:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:46 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:49 INFO - Installing pip.................done. 09:17:49 INFO - Return code: 0 09:17:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 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 0x1025bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9ea4ab3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.R3FckehF7H/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.htDqw2przm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:17:49 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 09:17:49 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 09:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:49 INFO - 'HOME': '/Users/cltbld', 09:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:49 INFO - 'LOGNAME': 'cltbld', 09:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:49 INFO - 'MOZ_NO_REMOTE': '1', 09:17:49 INFO - 'NO_EM_RESTART': '1', 09:17:49 INFO - 'PAGER': '/bin/cat', 09:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:49 INFO - 'PWD': '/builds/slave/test', 09:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:49 INFO - 'SHELL': '/bin/bash', 09:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:17:49 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:49 INFO - 'USER': 'cltbld', 09:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:49 INFO - 'XPC_FLAGS': '0x0', 09:17:49 INFO - 'XPC_SERVICE_NAME': '0', 09:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:49 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 09:17:49 INFO - Cleaning up... 09:17:49 INFO - Return code: 0 09:17:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9ea4ab3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.R3FckehF7H/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.htDqw2przm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:17:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:49 INFO - 'HOME': '/Users/cltbld', 09:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:49 INFO - 'LOGNAME': 'cltbld', 09:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:49 INFO - 'MOZ_NO_REMOTE': '1', 09:17:49 INFO - 'NO_EM_RESTART': '1', 09:17:49 INFO - 'PAGER': '/bin/cat', 09:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:49 INFO - 'PWD': '/builds/slave/test', 09:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:49 INFO - 'SHELL': '/bin/bash', 09:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:17:49 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:49 INFO - 'USER': 'cltbld', 09:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:49 INFO - 'XPC_FLAGS': '0x0', 09:17:49 INFO - 'XPC_SERVICE_NAME': '0', 09:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:50 INFO - Downloading/unpacking psutil>=0.7.1 09:17:50 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 09:17:50 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 09:17:50 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 09:17:50 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 09:17:50 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 09:17:50 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 09:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:53 INFO - Installing collected packages: psutil 09:17:53 INFO - Running setup.py install for psutil 09:17:53 INFO - building 'psutil._psutil_osx' extension 09:17:53 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 09:17:53 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 09:17:53 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 09:17:53 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 09:17:53 INFO - building 'psutil._psutil_posix' extension 09:17:53 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 09:17:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:17:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:17:53 INFO - ^ 09:17:53 INFO - 1 warning generated. 09:17:53 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 09:17:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:53 INFO - Successfully installed psutil 09:17:53 INFO - Cleaning up... 09:17:53 INFO - Return code: 0 09:17:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9ea4ab3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.R3FckehF7H/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.htDqw2przm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:17:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:17:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:53 INFO - 'HOME': '/Users/cltbld', 09:17:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:53 INFO - 'LOGNAME': 'cltbld', 09:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:53 INFO - 'MOZ_NO_REMOTE': '1', 09:17:53 INFO - 'NO_EM_RESTART': '1', 09:17:53 INFO - 'PAGER': '/bin/cat', 09:17:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:53 INFO - 'PWD': '/builds/slave/test', 09:17:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:53 INFO - 'SHELL': '/bin/bash', 09:17:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:17:53 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:17:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:53 INFO - 'USER': 'cltbld', 09:17:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:53 INFO - 'XPC_FLAGS': '0x0', 09:17:53 INFO - 'XPC_SERVICE_NAME': '0', 09:17:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:17:54 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 09:17:54 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 09:17:54 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 09:17:54 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 09:17:54 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 09:17:54 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 09:17:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:17:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:17:56 INFO - Installing collected packages: mozsystemmonitor 09:17:56 INFO - Running setup.py install for mozsystemmonitor 09:17:56 INFO - Successfully installed mozsystemmonitor 09:17:56 INFO - Cleaning up... 09:17:56 INFO - Return code: 0 09:17:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:17:56 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')]} 09:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:56 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')]} 09:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:56 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 0x1025bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9ea4ab3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.R3FckehF7H/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.htDqw2przm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:17:56 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 09:17:56 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 09:17:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:17:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:56 INFO - 'HOME': '/Users/cltbld', 09:17:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:56 INFO - 'LOGNAME': 'cltbld', 09:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:56 INFO - 'MOZ_NO_REMOTE': '1', 09:17:56 INFO - 'NO_EM_RESTART': '1', 09:17:56 INFO - 'PAGER': '/bin/cat', 09:17:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:56 INFO - 'PWD': '/builds/slave/test', 09:17:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:56 INFO - 'SHELL': '/bin/bash', 09:17:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:17:56 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:17:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:56 INFO - 'USER': 'cltbld', 09:17:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:56 INFO - 'XPC_FLAGS': '0x0', 09:17:56 INFO - 'XPC_SERVICE_NAME': '0', 09:17:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:57 INFO - Downloading/unpacking blobuploader==1.2.4 09:17:57 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 09:17:57 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 09:17:57 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 09:17:57 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 09:17:57 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 09:17:57 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 09:17:59 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:59 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 09:17:59 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 09:17:59 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 09:17:59 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 09:17:59 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 09:17:59 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 09:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:18:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18:00 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 09:18:00 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 09:18:00 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 09:18:00 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 09:18:00 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 09:18:00 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 09:18:00 INFO - Downloading docopt-0.6.1.tar.gz 09:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:00 INFO - Running setup.py install for blobuploader 09:18:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:18:01 INFO - Running setup.py install for requests 09:18:01 INFO - Running setup.py install for docopt 09:18:01 INFO - Successfully installed blobuploader requests docopt 09:18:01 INFO - Cleaning up... 09:18:01 INFO - Return code: 0 09:18:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:01 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 0x1025bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9ea4ab3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.R3FckehF7H/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.htDqw2przm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:18:01 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 09:18:01 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 09:18:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:18:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:01 INFO - 'HOME': '/Users/cltbld', 09:18:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:01 INFO - 'LOGNAME': 'cltbld', 09:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:01 INFO - 'MOZ_NO_REMOTE': '1', 09:18:01 INFO - 'NO_EM_RESTART': '1', 09:18:01 INFO - 'PAGER': '/bin/cat', 09:18:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:01 INFO - 'PWD': '/builds/slave/test', 09:18:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:01 INFO - 'SHELL': '/bin/bash', 09:18:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:18:01 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:18:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:01 INFO - 'USER': 'cltbld', 09:18:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:01 INFO - 'XPC_FLAGS': '0x0', 09:18:01 INFO - 'XPC_SERVICE_NAME': '0', 09:18:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-JCbnqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-vg6b3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-z3tKjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-NUuhYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-l6MCZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-rZAcGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-PfEXgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-F4hs2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-k3Zgn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:02 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-TmWQDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:03 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-0LeXiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:03 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-NtKQPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:03 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-SM_4PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:03 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-1hZ_85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:03 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-pBSQhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:03 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-WCVVX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:03 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-K81orY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:18:03 INFO - Running setup.py install for manifestparser 09:18:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:18:03 INFO - Running setup.py install for mozcrash 09:18:04 INFO - Running setup.py install for mozdebug 09:18:04 INFO - Running setup.py install for mozdevice 09:18:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:18:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:18:04 INFO - Running setup.py install for mozfile 09:18:04 INFO - Running setup.py install for mozhttpd 09:18:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:18:04 INFO - Running setup.py install for mozinfo 09:18:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:18:04 INFO - Running setup.py install for mozInstall 09:18:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:18:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:18:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:18:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:18:04 INFO - Running setup.py install for mozleak 09:18:05 INFO - Running setup.py install for mozlog 09:18:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:18:05 INFO - Running setup.py install for moznetwork 09:18:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:18:05 INFO - Running setup.py install for mozprocess 09:18:05 INFO - Running setup.py install for mozprofile 09:18:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:18:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:18:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:18:05 INFO - Running setup.py install for mozrunner 09:18:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:18:05 INFO - Running setup.py install for mozscreenshot 09:18:06 INFO - Running setup.py install for moztest 09:18:06 INFO - Running setup.py install for mozversion 09:18:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:18:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:18:06 INFO - Cleaning up... 09:18:06 INFO - Return code: 0 09:18:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:06 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 0x1025bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9ea4ab3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.R3FckehF7H/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.htDqw2przm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:18:06 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 09:18:06 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 09:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:06 INFO - 'HOME': '/Users/cltbld', 09:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:06 INFO - 'LOGNAME': 'cltbld', 09:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:06 INFO - 'MOZ_NO_REMOTE': '1', 09:18:06 INFO - 'NO_EM_RESTART': '1', 09:18:06 INFO - 'PAGER': '/bin/cat', 09:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:06 INFO - 'PWD': '/builds/slave/test', 09:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:06 INFO - 'SHELL': '/bin/bash', 09:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:18:06 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:06 INFO - 'USER': 'cltbld', 09:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:06 INFO - 'XPC_FLAGS': '0x0', 09:18:06 INFO - 'XPC_SERVICE_NAME': '0', 09:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:06 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-whurdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:06 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)) 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:06 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-pNBfQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:06 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)) 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:06 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Z0DFLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:06 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)) 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:06 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-_lWEaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:07 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)) 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:07 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-lTIkJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:07 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)) 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:07 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-IpDV1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:07 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)) 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:07 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-6Z9wOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:07 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)) 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:07 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ojXfgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:07 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)) 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:07 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-waJkpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:07 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)) 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:07 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-u3ts1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:07 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Ljisfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:07 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)) 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:07 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-UUeK4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:07 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)) 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:07 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-cRy99W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:08 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)) 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:08 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-6JSxvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:08 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)) 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:08 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-b_e0pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:08 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)) 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:08 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-dTunZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:08 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)) 09:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:08 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-H2ThCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:08 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)) 09:18:08 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)) 09:18:08 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)) 09:18:08 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)) 09:18:08 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)) 09:18:08 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)) 09:18:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:18:08 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 09:18:08 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 09:18:08 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 09:18:08 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 09:18:08 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 09:18:08 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 09:18:10 INFO - Downloading blessings-1.6.tar.gz 09:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:18:10 INFO - Installing collected packages: blessings 09:18:10 INFO - Running setup.py install for blessings 09:18:11 INFO - Successfully installed blessings 09:18:11 INFO - Cleaning up... 09:18:11 INFO - Return code: 0 09:18:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:18:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:11 INFO - Reading from file tmpfile_stdout 09:18:11 INFO - Current package versions: 09:18:11 INFO - blessings == 1.6 09:18:11 INFO - blobuploader == 1.2.4 09:18:11 INFO - docopt == 0.6.1 09:18:11 INFO - manifestparser == 1.1 09:18:11 INFO - mozInstall == 1.12 09:18:11 INFO - mozcrash == 0.17 09:18:11 INFO - mozdebug == 0.1 09:18:11 INFO - mozdevice == 0.48 09:18:11 INFO - mozfile == 1.2 09:18:11 INFO - mozhttpd == 0.7 09:18:11 INFO - mozinfo == 0.9 09:18:11 INFO - mozleak == 0.1 09:18:11 INFO - mozlog == 3.1 09:18:11 INFO - moznetwork == 0.27 09:18:11 INFO - mozprocess == 0.22 09:18:11 INFO - mozprofile == 0.28 09:18:11 INFO - mozrunner == 6.11 09:18:11 INFO - mozscreenshot == 0.1 09:18:11 INFO - mozsystemmonitor == 0.0 09:18:11 INFO - moztest == 0.7 09:18:11 INFO - mozversion == 1.4 09:18:11 INFO - psutil == 3.1.1 09:18:11 INFO - requests == 1.2.3 09:18:11 INFO - wsgiref == 0.1.2 09:18:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:11 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 0x1025bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9ea4ab3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.R3FckehF7H/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.htDqw2przm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:18:11 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 09:18:11 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 09:18:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:18:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:11 INFO - 'HOME': '/Users/cltbld', 09:18:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:11 INFO - 'LOGNAME': 'cltbld', 09:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:11 INFO - 'MOZ_NO_REMOTE': '1', 09:18:11 INFO - 'NO_EM_RESTART': '1', 09:18:11 INFO - 'PAGER': '/bin/cat', 09:18:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:11 INFO - 'PWD': '/builds/slave/test', 09:18:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:11 INFO - 'SHELL': '/bin/bash', 09:18:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:18:11 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:18:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:11 INFO - 'USER': 'cltbld', 09:18:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:11 INFO - 'XPC_FLAGS': '0x0', 09:18:11 INFO - 'XPC_SERVICE_NAME': '0', 09:18:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:11 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)) 09:18:11 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)) 09:18:11 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)) 09:18:11 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)) 09:18:11 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)) 09:18:11 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)) 09:18:11 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)) 09:18:11 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)) 09:18:11 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)) 09:18:11 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)) 09:18:11 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)) 09:18:11 INFO - Cleaning up... 09:18:11 INFO - Return code: 0 09:18:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9ea4ab3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.R3FckehF7H/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.htDqw2przm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:18:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:18:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:18:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:11 INFO - 'HOME': '/Users/cltbld', 09:18:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:11 INFO - 'LOGNAME': 'cltbld', 09:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:11 INFO - 'MOZ_NO_REMOTE': '1', 09:18:11 INFO - 'NO_EM_RESTART': '1', 09:18:11 INFO - 'PAGER': '/bin/cat', 09:18:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:11 INFO - 'PWD': '/builds/slave/test', 09:18:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:11 INFO - 'SHELL': '/bin/bash', 09:18:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:18:11 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:18:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:11 INFO - 'USER': 'cltbld', 09:18:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:11 INFO - 'XPC_FLAGS': '0x0', 09:18:11 INFO - 'XPC_SERVICE_NAME': '0', 09:18:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:12 INFO - Downloading/unpacking jsonschema 09:18:12 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 09:18:12 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 09:18:12 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 09:18:12 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 09:18:12 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 09:18:12 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 09:18:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:14 INFO - Downloading/unpacking functools32 (from jsonschema) 09:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:14 INFO - Downloading functools32-3.2.3-2.tar.gz 09:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:14 INFO - warning: no files found matching '*.txt' 09:18:14 INFO - no previously-included directories found matching 'build' 09:18:14 INFO - no previously-included directories found matching 'dist' 09:18:14 INFO - no previously-included directories found matching '.git*' 09:18:14 INFO - Installing collected packages: jsonschema, functools32 09:18:14 INFO - Running setup.py install for functools32 09:18:15 INFO - warning: no files found matching '*.txt' 09:18:15 INFO - no previously-included directories found matching 'build' 09:18:15 INFO - no previously-included directories found matching 'dist' 09:18:15 INFO - no previously-included directories found matching '.git*' 09:18:15 INFO - Successfully installed jsonschema functools32 09:18:15 INFO - Cleaning up... 09:18:15 INFO - Return code: 0 09:18:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:18:15 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')]} 09:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:15 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')]} 09:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:15 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 0x1025bfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025cf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9ea4ab3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.R3FckehF7H/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.htDqw2przm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 09:18:15 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 09:18:15 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 09:18:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:18:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:15 INFO - 'HOME': '/Users/cltbld', 09:18:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:15 INFO - 'LOGNAME': 'cltbld', 09:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:15 INFO - 'MOZ_NO_REMOTE': '1', 09:18:15 INFO - 'NO_EM_RESTART': '1', 09:18:15 INFO - 'PAGER': '/bin/cat', 09:18:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:15 INFO - 'PWD': '/builds/slave/test', 09:18:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:15 INFO - 'SHELL': '/bin/bash', 09:18:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:18:15 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:18:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:15 INFO - 'USER': 'cltbld', 09:18:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:15 INFO - 'XPC_FLAGS': '0x0', 09:18:15 INFO - 'XPC_SERVICE_NAME': '0', 09:18:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:15 INFO - Downloading/unpacking flake8 09:18:15 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 09:18:15 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 09:18:15 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 09:18:15 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 09:18:15 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 09:18:15 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 09:18:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:18:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:18:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:18:18 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 09:18:18 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 09:18:18 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 09:18:18 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 09:18:18 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 09:18:18 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 09:18:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:18:18 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 09:18:18 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 09:18:18 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 09:18:18 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 09:18:18 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 09:18:18 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 09:18:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:18:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:18:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:18:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:18:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:18:19 INFO - no previously-included directories found matching 'docs/_build' 09:18:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:18:19 INFO - Running setup.py install for pep8 09:18:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:18:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:18:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:18:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:18:19 INFO - no previously-included directories found matching 'docs/_build' 09:18:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:18:19 INFO - Cleaning up... 09:18:19 INFO - Return code: 0 09:18:19 INFO - Running post-action listener: _resource_record_post_action 09:18:19 INFO - Running post-action listener: _start_resource_monitoring 09:18:19 INFO - Starting resource monitoring. 09:18:19 INFO - ##### 09:18:19 INFO - ##### Running install step. 09:18:19 INFO - ##### 09:18:19 INFO - Running pre-action listener: _resource_record_pre_action 09:18:19 INFO - Running main action method: install 09:18:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:19 INFO - Reading from file tmpfile_stdout 09:18:19 INFO - Detecting whether we're running mozinstall >=1.0... 09:18:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:18:19 INFO - Reading from file tmpfile_stdout 09:18:19 INFO - Output received: 09:18:19 INFO - Usage: mozinstall [options] installer 09:18:19 INFO - Options: 09:18:19 INFO - -h, --help show this help message and exit 09:18:19 INFO - -d DEST, --destination=DEST 09:18:19 INFO - Directory to install application into. [default: 09:18:19 INFO - "/builds/slave/test"] 09:18:19 INFO - --app=APP Application being installed. [default: firefox] 09:18:19 INFO - mkdir: /builds/slave/test/build/application 09:18:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:18:52 INFO - Reading from file tmpfile_stdout 09:18:52 INFO - Output received: 09:18:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:18:52 INFO - Running post-action listener: _resource_record_post_action 09:18:52 INFO - ##### 09:18:52 INFO - ##### Running run-tests step. 09:18:52 INFO - ##### 09:18:52 INFO - Running pre-action listener: _resource_record_pre_action 09:18:52 INFO - Running main action method: run_tests 09:18:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:52 INFO - 2016-04-07 09:18:52.948 screenresolution[2275:21488] starting screenresolution argv=screenresolution get 09:18:52 INFO - 2016-04-07 09:18:52.950 screenresolution[2275:21488] Display 0: 1600x1200x32@60 09:18:52 INFO - 2016-04-07 09:18:52.956 screenresolution[2276:21492] starting screenresolution argv=screenresolution list 09:18:52 INFO - Available Modes on Display 0 09:18:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:53 INFO - Intel Iris: 09:18:53 INFO - Chipset Model: Intel Iris 09:18:53 INFO - Type: GPU 09:18:53 INFO - Bus: Built-In 09:18:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:53 INFO - Vendor: Intel (0x8086) 09:18:53 INFO - Device ID: 0x0a2e 09:18:53 INFO - Revision ID: 0x0009 09:18:53 INFO - Displays: 09:18:53 INFO - Display: 09:18:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:53 INFO - Display Serial Number: 1600x1200 60 09:18:53 INFO - Main Display: Yes 09:18:53 INFO - Mirror: Off 09:18:53 INFO - Online: Yes 09:18:53 INFO - Rotation: Supported 09:18:53 INFO - Return code: 0 09:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:53 INFO - Python 2.7.3 09:18:53 INFO - Return code: 0 09:18:53 INFO - grabbing minidump binary from tooltool 09:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:53 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10245ee00>, '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 0x1022c7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:18:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:18:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:53 INFO - Return code: 0 09:18:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:18:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:18:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:18:54 INFO - Return code: 0 09:18:54 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:18:54 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0169 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R3FckehF7H/Render', 09:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:54 INFO - 'HOME': '/Users/cltbld', 09:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:54 INFO - 'LOGNAME': 'cltbld', 09:18:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:54 INFO - 'MOZ_NO_REMOTE': '1', 09:18:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:54 INFO - 'NO_EM_RESTART': '1', 09:18:54 INFO - 'PAGER': '/bin/cat', 09:18:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:54 INFO - 'PWD': '/builds/slave/test', 09:18:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:54 INFO - 'SHELL': '/bin/bash', 09:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.htDqw2przm/Listeners', 09:18:54 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 09:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:54 INFO - 'USER': 'cltbld', 09:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:54 INFO - 'XPC_FLAGS': '0x0', 09:18:54 INFO - 'XPC_SERVICE_NAME': '0', 09:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:18:54 INFO - mozversion application_buildid: 20160406112610 09:18:54 INFO - mozversion application_changeset: 55f0de142be46043246b18108299aebf2bd95a64 09:18:54 INFO - mozversion application_display_name: Nightly 09:18:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:54 INFO - mozversion application_name: Firefox 09:18:54 INFO - mozversion application_remotingname: firefox 09:18:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:18:54 INFO - mozversion application_vendor: Mozilla 09:18:54 INFO - mozversion application_version: 48.0a1 09:18:54 INFO - mozversion platform_buildid: 20160406112610 09:18:54 INFO - mozversion platform_changeset: 55f0de142be46043246b18108299aebf2bd95a64 09:18:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:18:54 INFO - mozversion platform_version: 48.0a1 09:18:54 INFO - using testdate: 1460045934 09:18:54 INFO - actual date: 1460045934 09:18:54 INFO - starting webserver on 'localhost:54790' 09:18:54 INFO - SUITE-START | Running 2 tests 09:18:54 INFO - TEST-START | dromaeo_css 09:18:54 INFO - operating with platform_type : mac_ 09:18:54 INFO - Initialising browser for dromaeo_css test... 09:18:54 INFO - TEST-INFO | started process 2294 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpjllpv_/profile http://localhost:54790/getInfo.html) 09:18:57 INFO - PROCESS | 2294 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 09:18:57 INFO - PROCESS | 2294 | __metrics Screen width/height:1600/1200 09:18:57 INFO - PROCESS | 2294 | colorDepth:24 09:18:57 INFO - PROCESS | 2294 | Browser inner width/height: 1024/689 09:18:57 INFO - PROCESS | 2294 | __metrics 09:18:57 ERROR - PROCESS | 2294 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2881: TypeError: this.tabs is undefined 09:18:58 INFO - TEST-INFO | 2294: exit 0 09:18:58 INFO - Browser initialized. 09:18:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:58 INFO - TEST-INFO | started process 2297 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpjllpv_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:10 INFO - PROCESS | 2297 | RSS: Main: 247091200 09:19:10 INFO - PROCESS | 2297 | 09:19:59 INFO - PROCESS | 2297 | 1460045999731 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] 09:22:11 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:54790/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54790/tests/dromaeo/cssquery-ext.html) 09:22:12 INFO - PROCESS | 2297 | RSS: Main: 259493888 09:22:12 INFO - PROCESS | 2297 | 09:25:14 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:54790/tests/dromaeo/cssquery-ext.html (next: http://localhost:54790/tests/dromaeo/cssquery-jquery.html) 09:25:15 INFO - PROCESS | 2297 | RSS: Main: 271343616 09:25:15 INFO - PROCESS | 2297 | 09:28:16 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:54790/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54790/tests/dromaeo/cssquery-mootools.html) 09:28:17 INFO - PROCESS | 2297 | RSS: Main: 273395712 09:28:17 INFO - PROCESS | 2297 | 09:31:19 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:54790/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54790/tests/dromaeo/cssquery-prototype.html) 09:31:19 INFO - PROCESS | 2297 | RSS: Main: 274956288 09:31:19 INFO - PROCESS | 2297 | 09:34:22 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:54790/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54790/tests/dromaeo/cssquery-yui.html) 09:34:23 INFO - PROCESS | 2297 | RSS: Main: 277749760 09:34:23 INFO - PROCESS | 2297 | 09:37:25 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:54790/tests/dromaeo/cssquery-yui.html (next: http://localhost:54790/tests/dromaeo/cssquery-dojo.html) 09:37:25 INFO - PROCESS | 2297 | RSS: Main: 279040000 09:37:25 INFO - PROCESS | 2297 | 09:37:25 INFO - PROCESS | 2297 | __start_tp_report 09:37:25 INFO - PROCESS | 2297 | _x_x_mozilla_page_load 09:37:25 INFO - PROCESS | 2297 | _x_x_mozilla_page_load_details 09:37:25 INFO - PROCESS | 2297 | |i|pagename|runs| 09:37:25 INFO - PROCESS | 2297 | |0;dojo.html;3108.6269647642284;3473.836264851275;3482.7736197147183;3550.2012047289345;3560.9287814243717;5779.971100144498;5820.476157145856;5832.212651292074;5854.853628659275;5965.284165900092;5536.557075433966;5656.2929633795775;5711.029125048742;5838.2702162229725;5931.436513531207;5160.74196290185;5195.220716892459;5241.9213711794355;5253.474652534755;5293.9735301323435;5253.185756207775;5268.9473105269035;5281.287026251464;5302.549283310923;5307.708076055816;5150.459201783815;5198.636095473318;5241.606879484054;5248.422673505911;5269.657472264291;5221.06020916235;5251.343582052244;5265.420803711589;5267.894642107175;5310.176922577007;5051.3433253676785;5175.922361164586;5203.895922081575;5240.161574148118;5247.816326428557;5658.405867383948;5688.374278829326;5697.088465845445;5718.399568045378;5735.082386818089;6131.3868613138775;6176.69116544171;6186.876262474725;6189.071639254103;6230.034344676583;6115.48018418435;6143.757528502109;6198.752049917998;6242.719077641472;6245.313015568284;5985.970070149621;5989.9101513477335;6005.069214271751;6009.429104235091;6010.2186715726675;6303.936960630335;6341.682915854189;6348.238211414659;6366;6370.458511026522;6101.877962440726;6136.73353236607;6155.630662160284;6168.013117901114;6198.977168767193;6120.846978825565;6186.350433204539;6188.628682279078;6219.844503887439;6243.0635404688755;6122.785702500391;6149.631022138686;6216.7824126155365;6225.564210505221;6240.500759939194;5217.634765566374;6108.736706092798;6119.593952096689;6176.536759743038;6234.812955611339;4657.441107067174;4901.1913034349645;4922.138625740481;4936.7284799336385;4970.17992031318;4438.7114837535455;4448.733076015449;4463.977680111579;4464.799084041226;4508.436445444319;5634.352049514277;5795.681237531892;5865.472107510344;5887.735051922674;5907.556933230025;5506.311711036121;5856.238688970407;6198.194234749544;6227.408396202353;6234.501239900797;5854;5858.150568167592;5858.707064646751;5860.648361098348;5861.3552509223955;5302.337207849019;5390.272313237741;5405.594580406485;5418.945810541844;5419.376771671229;6214.782482613087;6298.811035668938;6301.684915754194;6348.269948955744;6376.840578985562;5919.970400147971;6209.96895015522;6221;6243.125962365363;6246.937530624636;5230.7384630769075;6305.306416294112;6379.712912918837;6386.87226255463;6393.041043843275;6116.701002199415;6121.331035925228;6185.474234689922;6193.907091393543;6199.3490683477585;6160.845978850564;6196.752129914753;6229.159063526366;6256.468200202851;6277.811665650038;4945.851624451272;6002.339742628395;6102.0651310563235;6184.350643182581;6194.4115309045565;6112.755489780359;6146.877062458815;6148.1699970503405;6167.229096362955;6170.444659980623;5006.849794506171;5863.970680146743;5927.644341339534;6092.116643086639;6107.083937409424;6022.698865056642;6120.143179954721;6143.573288551087;6144.402455361549;6155.753769849156;6012.218411606463;6022.36765139654;6040.577159598786;6040.6375617461335;6073.757049717962 09:37:25 INFO - PROCESS | 2297 | |1;ext.html;413.95032596088475;479.5396419437341;588.1618693361958;622.5299898576574;633.3792882974685;37016.629833701685;38075.667351642696;38131;38193;38309.42535861964;39476.21047579047;39938;40060.39909401354;40061.39907901377;40080.198396032065;9271.907280927206;9492.098250666175;9510.668506409109;9514.286428517855;9544.236461083097;9308.394954327981;9511.239100871948;9521.523923803818;9522.285828562852;9549.665761698307;9491.477968711719;9496.715098547054;9508.904910950905;9537.523123843815;9582.041795820433;9436.528173591327;9478.241740660731;9505.049495050474;9521.571529281164;9524.285678574135;9351.064893510662;9421.717348479557;9501.2874034447;9527.142557169818;9558.665446709401;38205;38212.80893595542;38470.80764596159;38724.80637596822;39029.41455878164;3093.8607762650736;3099.612548431446;3129.7339726123305;3200.2639392939523;3372.089535825338;28183;28401.147965561067;28686.569701454497;28960.710392896224;29150;2731.644886164806;2751.8073734838567;2784.1090850927712;2827.4910516107093;2857.0714517781803;490120;495558;501317.49341253063;501941.99999999633;502646;67922.3207767916;68691.31308686949;68809;68813;68929;74353.25646743464;75238.62380688061;75909.62045189849;75930.62034689791;75939;16667;16678;17051.573710657332;17149.657006859794;17300.048497332515;11483.655490335303;12378.133530652947;12870.806937895939;12982.545610903762;13178.670533236746;21499.0650841425;21596.568068638855;21650.133994640037;21683.891580541997;22286.88856555707;7939.325157361689;8959.820803583892;8999.37004409685;9196.540172991323;9228.538573071319;928.3594319919234;1003.4832061488339;1032.5714828346302;1119.7604790419161;1170.2615878843378;23793.52412951731;30393.24016899595;30960.690393096233;31191.064268071994;31247.375052498825;942.3074040580168;955.3408148377597;1032.1536340201112;1100.790849738563;1110.722319420145;12909.160904541362;14521.636959075897;14714.926425367805;14967.176805275596;15102.320395582225;636162;658893;660596;661870;663196;60597;61115.38884611097;61338.38661613416;61426.38573614296;61458.69270653708;1417.8369487508862;1432.1621851217155;1459.7153555056875;1469.5885152157414;1471.0732238689557;30332.545011824397;30906.845465772527;30985.53521697131;30994.690053100083;31615.683843162195;49750;49984.00031999267;50223.74888125536;50274;50449.747751261006;373802;374189;374260;380175;380538;33781.324373512885;43656.78171609249;44295.335569965835;44928.77535612301;45412.54587454083;43885.902852428946;44152;44209.55790442183;44234.11531769282;44309.33535997027;35955.82022089873;36073.0981725459;36194.91415257547;36809.263814724094;37224.62775372212;35771.71936032106;37052.62947370492;37343.81328093342;37565.624343756215;38154.0461488465;35222.47166292559;37054.8147259262;37254.627453725116;37255.44116838304;39073.609263907;26568.202953911412;30974.845125774227;31843;32040.198995025312;32184;23808.285751427484;24592.139275125286;24681.136160235023;26788;30370.088897333117 09:37:25 INFO - PROCESS | 2297 | |2;jquery.html;353.4715600177733;360.0422875152095;365.90669379308275;367.13539614208537;370.0803503294315;19048.809511904878;19353.612927741455;20462.488437789063;20466.488337791525;20518.589628207446;11603.303801771899;19192.328268510606;19198.52003699908;19225.134868930898;19626.018699065025;5092.108880945838;5233.2150177473495;5277.70972596507;5280.762365693534;5293.100172970604;3910.061585219555;5210.713410762407;5668.149777533362;5898.439648233411;5948.643081415107;4999.7500124993785;5731.111677689964;5844.26946631671;5910.527157827363;5921.378255283198;5378.91931621026;5636.605437619368;5643.238162848004;5654.406287339812;5878.88242235155;5315.202719592054;5317.361916569997;5419.783208671648;5425.430329815392;5425.9186112208345;12563.994880409637;12723.491060357575;12758.298293593849;12831.74336513274;13326.133801302982;1871.2421469304954;1910.121344181678;2055.619710353589;2176.5320456101854;2209.2930262316067;9657.082577155159;9790.5594248259;9967.601295948154;12706.110572259946;14143.29283535819;1612.8629066529359;1642.786437763083;1681.2854536821924;1698.9745153822703;1728.2222999650207;90183.54908225418;91824.54087729585;92908;92979.60530592047;93733;9131.223845973167;9758.755486238206;11149.275297105662;11151.888481115086;11158.776824463464;13615.591532254048;13651.795223071662;13678.811390177523;13688;13690.178589284675;8293.626786794608;8501.107383724744;8516.44643098209;8528.914710852936;8581.65673373058;8532.104129066483;8618.482891026559;8664.913350866536;8703;8708.08565100668;4379.5401482843845;4400.163968845909;4409.294512877924;4415.66882483815;4451.933221001687;1746.4847869878417;1825.178669598686;1832.7342535332302;1856.9442916712521;1869.2336142181641;851.5571902610608;942.6182396129578;978.9167920726818;991.940483570988;1022.9232807539464;19319.4204173875;19397.32109376165;20002.799972000095;20488.897555512423;20512;942.29328003997;964.1178321835442;995.144176008632;1025.4308858583559;1073.5544748929262;10855.457227138611;10984.06635435981;11008.559657613605;11019.504122314514;11076.723081923017;583139;586677;589649;590230;609628;112514.43742781234;114469.13827154221;115473;116086;117652.6469470623;1024.134606257711;1036.8392899100652;1037.3931250218707;1082.815921293382;1084.4198353880793;17891;18185;18209.45371638853;18240.72638910444;18384.172712227715;46376.768116159205;47490.76254618705;47583.76208118937;47778.28332575084;47842.56472305836;64897.02654460281;64944;68414.65792671005;69428.6528567354;76291;7727.961360193163;7834.569098699626;7847.882281765892;7960.562169080757;8027.8795818063945;10912.836307455553;12452.750944980868;12735.363231838557;12795.93602032021;12826.486940522274;10581.31221470602;10679.8932010682;11106;11178.496967636313;11189.552417903193;9665.951670241604;9957.950210248902;10494;10911.290766100177;11063.336199828036;10825.729356766144;11220.214584978972;11265.17156985146;11330.98021178098;11456.255343402652;7741.806454838674;7793.337566306927;7799.571023593757;7941.960290198512;8092.907457493163;9511.791229400242;10745.677629671123;10812.35125892449;10961.123110151328;11060.391678457761 09:37:25 INFO - PROCESS | 2297 | |3;mootools.html;1588.0709784775906;1597.0178340320704;1874.1004317927395;1959.373000639795;1973.9506512337198;8047;8726.650933962634;8733.432326898755;8939.195472407482;8944.463332200072;8686.652533898636;8723.4329768565;8723.91276087239;8821.558922053888;8897.866532002025;2170.8263338932925;2478.5042991401747;2504.4365017870923;2505.235903049569;2506.1854897158405;2074.4917495213695;2451.534208500379;2476.7399423060588;2482.7020757509126;2488.4401009772837;2470.468849197422;2473.010795681733;2478.7397323281066;2499;2499.3751562109474;2482.6524286599893;2492.626106084084;2493.5636263653787;2495.338735235164;2497.300755788382;2456.3981824452944;2482;2488.8506689598503;2491.9003239870385;2511.1713134665697;6740;7882.132965373804;8905.465672059698;8946.821063578693;8954.955225223897;5386.084365657847;5484.670919744828;5502.532284755801;5529.30883639545;5549.972250138723;3557.2351944332095;3592.3533763922483;3670.3576874046935;3679.742418030739;3681.7054635629083;1007.627177944158;1031.8606988056592;1034.1468288661883;1034.6482196053378;1036.217655669972;11786.88213117875;12002.039836813121;12027.93986030073;12035.976941959943;12041.39793010346;4076.3477843545484;4245.214635292481;4882.5605695486865;4974.1543937530705;4977.850664480072;8908.242799361997;9726.492393678991;10133.493325333704;10133.543990520444;10143.64497242593;5394.595405344615;5472.288602481652;5508.669479831223;5522.9447705523235;5528.834134975877;4174.352975288835;4413.713108647928;4885.755712214446;5008.6744361616375;5061.519155680205;3887.9805600971813;3922.6077392260545;3994.8801535953967;4001.239764444746;4012.3179059559943;1124.6344937895217;1317.9143355682043;1324.6489680234745;1331.8202042724301;1347.5014244729514;1061.559452827068;1072.0298130192189;1073.822819234833;1382.1292585670963;1393.1849867827232;8102.635381407851;8246.628901699438;8359.8328033439;8390.538520381439;8409.957950210332;493.75312343828085;495.9851204463872;496.5729472653515;498.4765995704496;519.3456245131161;6069.514438844882;6150.50795936324;6178.69106544671;6223.96888015566;6227.688615569204;10364.844527332096;10639.840402393971;10642.361458312525;10676.0925321347;10692.732681683021;7025.964870175617;10500.684979450629;10565;10577.524011419504;10801.189910756568;8299.709510167115;8358.164183581715;8414.915850841535;8427.705030324032;8459.49243045407;8082.595870206349;8096.190380961857;8147.063087744985;8182.7545173644885;8219.342452603896;9401.905980940102;9631.325807193429;9713.514324283899;9755.56099975517;9755.853662194932;9871.555779989774;9878.654247101604;9912.684395134436;10049.497525123568;10120.190384769348;6811.421029212573;7225.024621676217;7464.77605671809;7564.810879728051;7639.312461878458;7533.3973282138395;8111.513309201467;8187.5087494750505;8216.21945915137;8271.296939760188;6644.734210631714;7265.346118849329;7297.233790451926;7358.1538123116525;7463.253674632493;6006.33930267679;6150.415710507495;6405.711742971671;7193.424526037799;7225.5664660120565;6403.519736019724;7465;7682.154962953959;7684.46208765381;7717.691292348468;5965.28416590012;6824.078749368771;6950.930490695028;6973.232944376216;7029.191642961125;6899.275576064641;7015.298470152944;7062.1878483975;7107.822304442224;7120.287971202838 09:37:25 INFO - PROCESS | 2297 | |4;prototype.html;327.3142765905428;329.0047605991874;334.8526648274759;339.9919239455015;340.7120982769559;5603.60774745768;6854.177498700156;6891.34532219439;6899.724011039559;6902.068220790192;6846.760363387282;6849.589024658524;6851.177858656968;6872.381485666289;6878.1058462399815;4753.4533528644215;4815.446223684286;4936.185529387647;4971.105201063807;4973;4915.5330243626795;4953.0589188054155;4955.628327875405;4961.231009193581;4964.67729597575;4892.6575139740235;4925.039617274633;4940.011997600476;4967.7019378837385;4986.725730084844;4967.403911530621;4980.352554167935;4981.302617633516;4993.5755460785695;5022.472640372781;4279.186954478639;4288.142371525676;4299.591538803808;4300.591443812833;4300.6344460720875;6310.1481300024825;6516.1203237562795;6522.902156467657;6559.34406559345;6614.470842332602;5853.209816674779;5896.823095307148;5929.288485381739;5936.910946335765;5945.851353716192;5837.970810145965;7270.127584689871;7274.272572742736;7293.416526677906;7329.19378868324;4801;4808.855734327975;4813.66304358695;4817.156997525454;4838.346823178897;6930.3762661360215;7724.188960159215;7730.884036739453;7736.613169341566;7770.184130666312;4763.095011947719;4821.855344339676;4844.636652251095;4849.587785038311;4874.46380898101;4925.965547235049;4930.2851086592245;4940.283658869444;4943.876403089952;4944.851654450372;5826.82519524415;5844.386339434298;5851.239338886003;5856.12158176277;5867.354590994987;7246;7310.159331676925;7320.926790732131;7322.816929576697;7336.7432139874845;1636.7790348302826;1677.9077150756825;1694.6017685843817;1701.7277235642239;1719.5872990482194;1097.8133717268083;1103.8951299626522;1116.5422176907591;1117.7261570915177;1126.048489026771;5428.647137936022;6688.4649228061635;6713.395794378529;6726.529142959946;6729.360710732571;7546.773596792105;7565.167831538526;7567.167611562723;7583.810404739926;7597.278258565428;6665.600063996176;6673.232578253563;6698.5980841149685;6701.731930722716;6718.227403848522;2960.860068873499;3601.2977469393218;3993.740406873544;4016.5380981186954;4019.7789121598594;6118.479929205978;6534.248561415403;6791.898121528181;7990.64042118106;7992.200779922077;6841.281665425058;7721.613919304013;7937.2459616336355;7946.245106714853;7966.681332746741;7931.603419828985;7954.045514538292;7974.162712915177;8000.15998320168;8013.4791238569305;6152.600080994721;7320.890186647098;7627.351675107678;7665.69337226527;7674.462787605037;7360.926390736024;7424;7450.143233528213;7451.888221676788;7464.664090115849;6353.4599559038;6415.839604009937;7875.527468351917;7878.9212107880485;7954.602269886598;4652.185867473247;4727.952720472751;4729.527047295243;4733.290006499053;4733.479317275028;5490.862728431821;7392.186859445349;7632.160462349026;7653.196414376633;7669.6548655309525;7348.301911318416;7387.1874093848555;7409.62951852416;7426.443016773872;7430.888536671846;6051.5461340400525;7270.927290727025;7354.301341372561;7384.040074790243;7387.187409385071;7192.568445893263;7197.244289349543;7213.2065472799;7218.747343842941;7231.096112985877;7129.857402852018;7296.1974182838785;7379.483436159418;7386.704531818882;7386.926130738624;6301;7111.004459375588;7207.495475316888;7218.169910460364;7239.674214660247 09:37:25 INFO - PROCESS | 2297 | |5;yui.html;645.22572912505;665.5707068940534;705.2752009588571;714.9678264478098;752.8456666383388;12211.755764884698;12583.056270779694;13914.51299204528;14577.854221457783;14699.265036748173;11865.52537898483;13329.333533323343;13570.525031623894;13605.319734013308;13811.309434528284;746.5073051785809;880.0978996528578;896.9730908072768;907.9727608171765;908.4413085952158;870;870.582120582121;872.2498651159997;892.2148509311796;894.4588523943019;943.4386540008718;951.8286708392486;952.7903861150536;954.2985905359556;956.2541217850088;779;912.7535565397338;913.8720579118929;938.713692323841;948.0803620488116;679.0934102972489;695.4366962760181;699.7725739134751;704.4505285877024;711.7153138744492;13020.088593798439;13072.215667060007;13182.73634527304;13203.735925281537;13246.40391182399;8919.197272245465;8928.642854285821;9366.250699943988;10453.372797632166;10985.780284394346;9151.450912945245;9202;9318.673846415411;9332.39339442934;9365.859512107323;3341.599008119017;3402.2855200407944;3416.7437442191685;3471.6354782747953;3489.494023366623;77774.22225777726;77995.61002194953;78467.6076619619;81917;82247.35505289861;6851.48613853963;7593.848123037508;8138.796530086795;8165.469244499089;8207.630656620466;18721;18956.905215473835;18999.62000759977;19001.049947502568;19034.143463544173;7221.494495385377;7316.378107860892;7330.230325815819;7340.743073992385;7370.189279179286;3968.523777146761;4005.859794907164;4019.256437559061;4025.959740402617;4040.0303927057294;3498.107982464506;3500.7724497907557;3567.0725611341236;3567.9108022299133;3590.9102272443397;622.3682961793783;633.5343522510951;635.5138319185827;646.0438550944629;646.877093352271;489.1183518193749;524.3655177235512;525.7108590275333;580.1298052920619;583.8306891001647;6577.506686998398;8051;8173.060098088677;8267.379946504036;8308.376871734645;524.5960610330101;551.0486743173788;578.5227187570272;595.928488581373;599.6252342286072;3057.5260834570677;3722.274156539449;3776.792276424823;3799.981000094982;3887.1642596841816;85750.57124714337;86739;87930;89012.5549372249;91196.54401727949;77220;77500;77880.83178752323;78260.2173978253;79416.6029169862;70405.64797175981;71092.93360599595;71336.64331678412;72664;73861.26138738652;7658.578778167254;7726.111497177896;7727.111382190895;7745.883811742716;7824.935674852035;49756;50926.490735093656;52216.73891630518;52281.7385913068;53550;58159.70920145372;59794.402055978884;65341.346586533524;68305;71875.64062179656;9451.858222126537;10615.89384106149;10628.734281643021;10639.89360106389;11042.447877606248;11579.363135027317;11624.535018599161;11670.299782013106;11670.69540723559;12068.39658017078;9557.904420955701;11162.441877906234;11269.295369111518;11294.774104518028;11557.88442115568;11363.034142098015;11387.373694446553;11493.540258389905;11832.585859494877;11952.880471195176;11164.665060048212;11239.213255072067;11558.595449159575;11651.825222621837;11732.12009099338;8985.607230879225;9220.14865728881;9387.15515603599;9392.201662858733;9845.556949937127;7894.842103158019;9540.475273860004;9596.136347728738;9646.758831029281;9672.22622190209 09:37:25 INFO - PROCESS | 2297 | __end_tp_report 09:37:25 INFO - PROCESS | 2297 | __start_cc_report 09:37:25 INFO - PROCESS | 2297 | _x_x_mozilla_cycle_collect,390 09:37:25 INFO - PROCESS | 2297 | __end_cc_report 09:37:25 INFO - PROCESS | 2297 | __startTimestamp1460047045517__endTimestamp 09:37:25 INFO - PROCESS | 2297 | 09:37:25 INFO - PROCESS | 2297 | ------- Summary: start ------- 09:37:25 INFO - PROCESS | 2297 | Number of tests: 6 09:37:25 INFO - PROCESS | 2297 | 09:37:25 INFO - PROCESS | 2297 | [#0] dojo.html Cycles:165 Average:5743.30 Median:6005.07 stddev:631.90 (10.5%) stddev-sans-first:599.09 09:37:25 INFO - PROCESS | 2297 | Values: 3108.6 3473.8 3482.8 3550.2 3560.9 5780.0 5820.5 5832.2 5854.9 5965.3 5536.6 5656.3 5711.0 5838.3 5931.4 5160.7 5195.2 5241.9 5253.5 5294.0 5253.2 5268.9 5281.3 5302.5 5307.7 5150.5 5198.6 5241.6 5248.4 5269.7 5221.1 5251.3 5265.4 5267.9 5310.2 5051.3 5175.9 5203.9 5240.2 5247.8 5658.4 5688.4 5697.1 5718.4 5735.1 6131.4 6176.7 6186.9 6189.1 6230.0 6115.5 6143.8 6198.8 6242.7 6245.3 5986.0 5989.9 6005.1 6009.4 6010.2 6303.9 6341.7 6348.2 6366.0 6370.5 6101.9 6136.7 6155.6 6168.0 6199.0 6120.8 6186.4 6188.6 6219.8 6243.1 6122.8 6149.6 6216.8 6225.6 6240.5 5217.6 6108.7 6119.6 6176.5 6234.8 4657.4 4901.2 4922.1 4936.7 4970.2 4438.7 4448.7 4464.0 4464.8 4508.4 5634.4 5795.7 5865.5 5887.7 5907.6 5506.3 5856.2 6198.2 6227.4 6234.5 5854.0 5858.2 5858.7 5860.6 5861.4 5302.3 5390.3 5405.6 5418.9 5419.4 6214.8 6298.8 6301.7 6348.3 6376.8 5920.0 6210.0 6221.0 6243.1 6246.9 5230.7 6305.3 6379.7 6386.9 6393.0 6116.7 6121.3 6185.5 6193.9 6199.3 6160.8 6196.8 6229.2 6256.5 6277.8 4945.9 6002.3 6102.1 6184.4 6194.4 6112.8 6146.9 6148.2 6167.2 6170.4 5006.8 5864.0 5927.6 6092.1 6107.1 6022.7 6120.1 6143.6 6144.4 6155.8 6012.2 6022.4 6040.6 6040.6 6073.8 09:37:25 INFO - PROCESS | 2297 | 09:37:25 INFO - PROCESS | 2297 | [#1] ext.html Cycles:180 Average:66063.01 Median:38199.00 stddev:139702.89 (365.7%) stddev-sans-first:140007.84 09:37:25 INFO - PROCESS | 2297 | Values: 414.0 479.5 588.2 622.5 633.4 37016.6 38075.7 38131.0 38193.0 38309.4 39476.2 39938.0 40060.4 40061.4 40080.2 9271.9 9492.1 9510.7 9514.3 9544.2 9308.4 9511.2 9521.5 9522.3 9549.7 9491.5 9496.7 9508.9 9537.5 9582.0 9436.5 9478.2 9505.0 9521.6 9524.3 9351.1 9421.7 9501.3 9527.1 9558.7 38205.0 38212.8 38470.8 38724.8 39029.4 3093.9 3099.6 3129.7 3200.3 3372.1 28183.0 28401.1 28686.6 28960.7 29150.0 2731.6 2751.8 2784.1 2827.5 2857.1 490120.0 495558.0 501317.5 501942.0 502646.0 67922.3 68691.3 68809.0 68813.0 68929.0 74353.3 75238.6 75909.6 75930.6 75939.0 16667.0 16678.0 17051.6 17149.7 17300.0 11483.7 12378.1 12870.8 12982.5 13178.7 21499.1 21596.6 21650.1 21683.9 22286.9 7939.3 8959.8 8999.4 9196.5 9228.5 928.4 1003.5 1032.6 1119.8 1170.3 23793.5 30393.2 30960.7 31191.1 31247.4 942.3 955.3 1032.2 1100.8 1110.7 12909.2 14521.6 14714.9 14967.2 15102.3 636162.0 658893.0 660596.0 661870.0 663196.0 60597.0 61115.4 61338.4 61426.4 61458.7 1417.8 1432.2 1459.7 1469.6 1471.1 30332.5 30906.8 30985.5 30994.7 31615.7 49750.0 49984.0 50223.7 50274.0 50449.7 373802.0 374189.0 374260.0 380175.0 380538.0 33781.3 43656.8 44295.3 44928.8 45412.5 43885.9 44152.0 44209.6 44234.1 44309.3 35955.8 36073.1 36194.9 36809.3 37224.6 35771.7 37052.6 37343.8 37565.6 38154.0 35222.5 37054.8 37254.6 37255.4 39073.6 26568.2 30974.8 31843.0 32040.2 32184.0 23808.3 24592.1 24681.1 26788.0 30370.1 09:37:25 INFO - PROCESS | 2297 | 09:37:25 INFO - PROCESS | 2297 | [#2] jquery.html Cycles:180 Average:32770.70 Median:356.76 stddev:97993.11 (27467.8%) stddev-sans-first:98237.77 09:37:25 INFO - PROCESS | 2297 | Values: 353.5 360.0 365.9 367.1 370.1 19048.8 19353.6 20462.5 20466.5 20518.6 11603.3 19192.3 19198.5 19225.1 19626.0 5092.1 5233.2 5277.7 5280.8 5293.1 3910.1 5210.7 5668.1 5898.4 5948.6 4999.8 5731.1 5844.3 5910.5 5921.4 5378.9 5636.6 5643.2 5654.4 5878.9 5315.2 5317.4 5419.8 5425.4 5425.9 12564.0 12723.5 12758.3 12831.7 13326.1 1871.2 1910.1 2055.6 2176.5 2209.3 9657.1 9790.6 9967.6 12706.1 14143.3 1612.9 1642.8 1681.3 1699.0 1728.2 90183.5 91824.5 92908.0 92979.6 93733.0 9131.2 9758.8 11149.3 11151.9 11158.8 13615.6 13651.8 13678.8 13688.0 13690.2 8293.6 8501.1 8516.4 8528.9 8581.7 8532.1 8618.5 8664.9 8703.0 8708.1 4379.5 4400.2 4409.3 4415.7 4451.9 1746.5 1825.2 1832.7 1856.9 1869.2 851.6 942.6 978.9 991.9 1022.9 19319.4 19397.3 20002.8 20488.9 20512.0 942.3 964.1 995.1 1025.4 1073.6 10855.5 10984.1 11008.6 11019.5 11076.7 583139.0 586677.0 589649.0 590230.0 609628.0 112514.4 114469.1 115473.0 116086.0 117652.6 1024.1 1036.8 1037.4 1082.8 1084.4 17891.0 18185.0 18209.5 18240.7 18384.2 46376.8 47490.8 47583.8 47778.3 47842.6 64897.0 64944.0 68414.7 69428.7 76291.0 7728.0 7834.6 7847.9 7960.6 8027.9 10912.8 12452.8 12735.4 12795.9 12826.5 10581.3 10679.9 11106.0 11178.5 11189.6 9666.0 9958.0 10494.0 10911.3 11063.3 10825.7 11220.2 11265.2 11331.0 11456.3 7741.8 7793.3 7799.6 7942.0 8092.9 9511.8 10745.7 10812.4 10961.1 11060.4 09:37:25 INFO - PROCESS | 2297 | 09:37:25 INFO - PROCESS | 2297 | [#3] mootools.html Cycles:180 Average:5973.76 Median:5433.44 stddev:3178.99 (58.5%) stddev-sans-first:3170.82 09:37:25 INFO - PROCESS | 2297 | Values: 1588.1 1597.0 1874.1 1959.4 1974.0 8047.0 8726.7 8733.4 8939.2 8944.5 8686.7 8723.4 8723.9 8821.6 8897.9 2170.8 2478.5 2504.4 2505.2 2506.2 2074.5 2451.5 2476.7 2482.7 2488.4 2470.5 2473.0 2478.7 2499.0 2499.4 2482.7 2492.6 2493.6 2495.3 2497.3 2456.4 2482.0 2488.9 2491.9 2511.2 6740.0 7882.1 8905.5 8946.8 8955.0 5386.1 5484.7 5502.5 5529.3 5550.0 3557.2 3592.4 3670.4 3679.7 3681.7 1007.6 1031.9 1034.1 1034.6 1036.2 11786.9 12002.0 12027.9 12036.0 12041.4 4076.3 4245.2 4882.6 4974.2 4977.9 8908.2 9726.5 10133.5 10133.5 10143.6 5394.6 5472.3 5508.7 5522.9 5528.8 4174.4 4413.7 4885.8 5008.7 5061.5 3888.0 3922.6 3994.9 4001.2 4012.3 1124.6 1317.9 1324.6 1331.8 1347.5 1061.6 1072.0 1073.8 1382.1 1393.2 8102.6 8246.6 8359.8 8390.5 8410.0 493.8 496.0 496.6 498.5 519.3 6069.5 6150.5 6178.7 6224.0 6227.7 10364.8 10639.8 10642.4 10676.1 10692.7 7026.0 10500.7 10565.0 10577.5 10801.2 8299.7 8358.2 8414.9 8427.7 8459.5 8082.6 8096.2 8147.1 8182.8 8219.3 9401.9 9631.3 9713.5 9755.6 9755.9 9871.6 9878.7 9912.7 10049.5 10120.2 6811.4 7225.0 7464.8 7564.8 7639.3 7533.4 8111.5 8187.5 8216.2 8271.3 6644.7 7265.3 7297.2 7358.2 7463.3 6006.3 6150.4 6405.7 7193.4 7225.6 6403.5 7465.0 7682.2 7684.5 7717.7 5965.3 6824.1 6950.9 6973.2 7029.2 6899.3 7015.3 7062.2 7107.8 7120.3 09:37:25 INFO - PROCESS | 2297 | 09:37:25 INFO - PROCESS | 2297 | [#4] prototype.html Cycles:180 Average:5903.76 Median:6640.04 stddev:1892.27 (28.5%) stddev-sans-first:1850.71 09:37:25 INFO - PROCESS | 2297 | Values: 327.3 329.0 334.9 340.0 340.7 5603.6 6854.2 6891.3 6899.7 6902.1 6846.8 6849.6 6851.2 6872.4 6878.1 4753.5 4815.4 4936.2 4971.1 4973.0 4915.5 4953.1 4955.6 4961.2 4964.7 4892.7 4925.0 4940.0 4967.7 4986.7 4967.4 4980.4 4981.3 4993.6 5022.5 4279.2 4288.1 4299.6 4300.6 4300.6 6310.1 6516.1 6522.9 6559.3 6614.5 5853.2 5896.8 5929.3 5936.9 5945.9 5838.0 7270.1 7274.3 7293.4 7329.2 4801.0 4808.9 4813.7 4817.2 4838.3 6930.4 7724.2 7730.9 7736.6 7770.2 4763.1 4821.9 4844.6 4849.6 4874.5 4926.0 4930.3 4940.3 4943.9 4944.9 5826.8 5844.4 5851.2 5856.1 5867.4 7246.0 7310.2 7320.9 7322.8 7336.7 1636.8 1677.9 1694.6 1701.7 1719.6 1097.8 1103.9 1116.5 1117.7 1126.0 5428.6 6688.5 6713.4 6726.5 6729.4 7546.8 7565.2 7567.2 7583.8 7597.3 6665.6 6673.2 6698.6 6701.7 6718.2 2960.9 3601.3 3993.7 4016.5 4019.8 6118.5 6534.2 6791.9 7990.6 7992.2 6841.3 7721.6 7937.2 7946.2 7966.7 7931.6 7954.0 7974.2 8000.2 8013.5 6152.6 7320.9 7627.4 7665.7 7674.5 7360.9 7424.0 7450.1 7451.9 7464.7 6353.5 6415.8 7875.5 7878.9 7954.6 4652.2 4728.0 4729.5 4733.3 4733.5 5490.9 7392.2 7632.2 7653.2 7669.7 7348.3 7387.2 7409.6 7426.4 7430.9 6051.5 7270.9 7354.3 7384.0 7387.2 7192.6 7197.2 7213.2 7218.7 7231.1 7129.9 7296.2 7379.5 7386.7 7386.9 6301.0 7111.0 7207.5 7218.2 7239.7 09:37:25 INFO - PROCESS | 2297 | 09:37:25 INFO - PROCESS | 2297 | [#5] yui.html Cycles:180 Average:17751.92 Median:672.33 stddev:25406.64 (3778.9%) stddev-sans-first:25445.44 09:37:25 INFO - PROCESS | 2297 | Values: 645.2 665.6 705.3 715.0 752.8 12211.8 12583.1 13914.5 14577.9 14699.3 11865.5 13329.3 13570.5 13605.3 13811.3 746.5 880.1 897.0 908.0 908.4 870.0 870.6 872.2 892.2 894.5 943.4 951.8 952.8 954.3 956.3 779.0 912.8 913.9 938.7 948.1 679.1 695.4 699.8 704.5 711.7 13020.1 13072.2 13182.7 13203.7 13246.4 8919.2 8928.6 9366.3 10453.4 10985.8 9151.5 9202.0 9318.7 9332.4 9365.9 3341.6 3402.3 3416.7 3471.6 3489.5 77774.2 77995.6 78467.6 81917.0 82247.4 6851.5 7593.8 8138.8 8165.5 8207.6 18721.0 18956.9 18999.6 19001.0 19034.1 7221.5 7316.4 7330.2 7340.7 7370.2 3968.5 4005.9 4019.3 4026.0 4040.0 3498.1 3500.8 3567.1 3567.9 3590.9 622.4 633.5 635.5 646.0 646.9 489.1 524.4 525.7 580.1 583.8 6577.5 8051.0 8173.1 8267.4 8308.4 524.6 551.0 578.5 595.9 599.6 3057.5 3722.3 3776.8 3800.0 3887.2 85750.6 86739.0 87930.0 89012.6 91196.5 77220.0 77500.0 77880.8 78260.2 79416.6 70405.6 71092.9 71336.6 72664.0 73861.3 7658.6 7726.1 7727.1 7745.9 7824.9 49756.0 50926.5 52216.7 52281.7 53550.0 58159.7 59794.4 65341.3 68305.0 71875.6 9451.9 10615.9 10628.7 10639.9 11042.4 11579.4 11624.5 11670.3 11670.7 12068.4 9557.9 11162.4 11269.3 11294.8 11557.9 11363.0 11387.4 11493.5 11832.6 11952.9 11164.7 11239.2 11558.6 11651.8 11732.1 8985.6 9220.1 9387.2 9392.2 9845.6 7894.8 9540.5 9596.1 9646.8 9672.2 09:37:25 INFO - PROCESS | 2297 | -------- Summary: end -------- 09:37:25 INFO - PROCESS | 2297 | 09:37:26 INFO - TEST-INFO | 2297: exit 0 09:37:26 INFO - TEST-OK | dromaeo_css | took 1111364ms 09:37:26 INFO - TEST-START | kraken 09:37:26 INFO - operating with platform_type : mac_ 09:37:26 INFO - Initialising browser for kraken test... 09:37:26 INFO - TEST-INFO | started process 2302 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmp1uqBwi/profile http://localhost:54790/getInfo.html) 09:37:28 INFO - PROCESS | 2302 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 09:37:28 INFO - PROCESS | 2302 | __metrics Screen width/height:1600/1200 09:37:28 INFO - PROCESS | 2302 | colorDepth:24 09:37:28 INFO - PROCESS | 2302 | Browser inner width/height: 1024/689 09:37:28 INFO - PROCESS | 2302 | __metrics 09:37:29 ERROR - PROCESS | 2302 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2881: TypeError: this.tabs is undefined 09:37:29 INFO - TEST-INFO | 2302: exit 0 09:37:29 INFO - Browser initialized. 09:37:29 INFO - Running cycle 1/1 for kraken test... 09:37:29 INFO - TEST-INFO | started process 2305 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmp1uqBwi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:41 INFO - PROCESS | 2305 | RSS: Main: 247111680 09:37:41 INFO - PROCESS | 2305 | 09:37:44 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54790/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:45 INFO - PROCESS | 2305 | RSS: Main: 259276800 09:37:45 INFO - PROCESS | 2305 | 09:37:47 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54790/tests/kraken/driver.html?testName=audio-dft) 09:37:48 INFO - PROCESS | 2305 | RSS: Main: 268328960 09:37:48 INFO - PROCESS | 2305 | 09:37:51 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54790/tests/kraken/driver.html?testName=audio-fft) 09:37:52 INFO - PROCESS | 2305 | RSS: Main: 271634432 09:37:52 INFO - PROCESS | 2305 | 09:37:54 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54790/tests/kraken/driver.html?testName=audio-oscillator) 09:37:55 INFO - PROCESS | 2305 | RSS: Main: 274034688 09:37:55 INFO - PROCESS | 2305 | 09:37:57 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54790/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:37:58 INFO - PROCESS | 2305 | RSS: Main: 273940480 09:37:58 INFO - PROCESS | 2305 | 09:38:01 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54790/tests/kraken/driver.html?testName=imaging-darkroom) 09:38:01 INFO - PROCESS | 2305 | RSS: Main: 274079744 09:38:01 INFO - PROCESS | 2305 | 09:38:04 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54790/tests/kraken/driver.html?testName=imaging-desaturate) 09:38:05 INFO - PROCESS | 2305 | RSS: Main: 274223104 09:38:05 INFO - PROCESS | 2305 | 09:38:08 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54790/tests/kraken/driver.html?testName=json-parse-financial) 09:38:08 INFO - PROCESS | 2305 | RSS: Main: 274526208 09:38:08 INFO - PROCESS | 2305 | 09:38:10 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54790/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:38:10 INFO - PROCESS | 2305 | RSS: Main: 274661376 09:38:10 INFO - PROCESS | 2305 | 09:38:12 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54790/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:38:13 INFO - PROCESS | 2305 | RSS: Main: 274468864 09:38:13 INFO - PROCESS | 2305 | 09:38:15 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54790/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:38:16 INFO - PROCESS | 2305 | RSS: Main: 274821120 09:38:16 INFO - PROCESS | 2305 | 09:38:18 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54790/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:38:19 INFO - PROCESS | 2305 | RSS: Main: 274874368 09:38:19 INFO - PROCESS | 2305 | 09:38:22 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54790/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:22 INFO - PROCESS | 2305 | RSS: Main: 274780160 09:38:22 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:54790/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54790/tests/kraken/driver.html?testName=ai-astar) 09:38:24 INFO - PROCESS | 2305 | RSS: Main: 276467712 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | __start_tp_report 09:38:24 INFO - PROCESS | 2305 | _x_x_mozilla_page_load 09:38:24 INFO - PROCESS | 2305 | _x_x_mozilla_page_load_details 09:38:24 INFO - PROCESS | 2305 | |i|pagename|runs| 09:38:24 INFO - PROCESS | 2305 | |0;ai-astar;132;156;161;116;116;129;115;118;115;115 09:38:24 INFO - PROCESS | 2305 | |1;audio-beat-detection;154;143;132;176;119;168;149;153;195;117 09:38:24 INFO - PROCESS | 2305 | |2;audio-dft;201;199;198;209;222;248;272;202;213;247 09:38:24 INFO - PROCESS | 2305 | |3;audio-fft;73;82;84;154;87;48;65;91;158;75 09:38:24 INFO - PROCESS | 2305 | |4;audio-oscillator;69;122;169;77;80;86;148;116;119;105 09:38:24 INFO - PROCESS | 2305 | |5;imaging-gaussian-blur;97;130;89;104;113;130;117;86;90;86 09:38:24 INFO - PROCESS | 2305 | |6;imaging-darkroom;129;97;97;98;96;105;131;96;96;94 09:38:24 INFO - PROCESS | 2305 | |7;imaging-desaturate;99;105;99;107;140;100;114;100;98;76 09:38:24 INFO - PROCESS | 2305 | |8;json-parse-financial;57;59;56;56;57;55;61;66;58;61 09:38:24 INFO - PROCESS | 2305 | |9;json-stringify-tinderbox;66;68;72;75;71;91;108;101;102;84 09:38:24 INFO - PROCESS | 2305 | |10;stanford-crypto-aes;88;85;85;85;88;87;86;86;88;106 09:38:24 INFO - PROCESS | 2305 | |11;stanford-crypto-ccm;124;129;148;115;148;116;146;141;132;147 09:38:24 INFO - PROCESS | 2305 | |12;stanford-crypto-pbkdf2;141;154;142;142;143;160;141;144;165;139 09:38:24 INFO - PROCESS | 2305 | |13;stanford-crypto-sha256-iterative;70;68;69;67;70;68;58;63;65;67 09:38:24 INFO - PROCESS | 2305 | __end_tp_report 09:38:24 INFO - PROCESS | 2305 | __start_cc_report 09:38:24 INFO - PROCESS | 2305 | _x_x_mozilla_cycle_collect,974 09:38:24 INFO - PROCESS | 2305 | __end_cc_report 09:38:24 INFO - PROCESS | 2305 | __startTimestamp1460047104647__endTimestamp 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | ------- Summary: start ------- 09:38:24 INFO - PROCESS | 2305 | Number of tests: 14 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#0] ai-astar Cycles:10 Average:127.30 Median:123.50 stddev:17.58 (14.2%) stddev-sans-first:18.56 09:38:24 INFO - PROCESS | 2305 | Values: 132.0 156.0 161.0 116.0 116.0 129.0 115.0 118.0 115.0 115.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#1] audio-beat-detection Cycles:10 Average:150.60 Median:153.50 stddev:24.65 (16.1%) stddev-sans-first:26.12 09:38:24 INFO - PROCESS | 2305 | Values: 154.0 143.0 132.0 176.0 119.0 168.0 149.0 153.0 195.0 117.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#2] audio-dft Cycles:10 Average:221.10 Median:217.50 stddev:25.80 (11.9%) stddev-sans-first:26.32 09:38:24 INFO - PROCESS | 2305 | Values: 201.0 199.0 198.0 209.0 222.0 248.0 272.0 202.0 213.0 247.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#3] audio-fft Cycles:10 Average:91.70 Median:78.50 stddev:36.06 (45.9%) stddev-sans-first:37.61 09:38:24 INFO - PROCESS | 2305 | Values: 73.0 82.0 84.0 154.0 87.0 48.0 65.0 91.0 158.0 75.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#4] audio-oscillator Cycles:10 Average:109.10 Median:119.00 stddev:32.40 (27.2%) stddev-sans-first:30.95 09:38:24 INFO - PROCESS | 2305 | Values: 69.0 122.0 169.0 77.0 80.0 86.0 148.0 116.0 119.0 105.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#5] imaging-gaussian-blur Cycles:10 Average:104.20 Median:86.00 stddev:17.38 (20.2%) stddev-sans-first:18.24 09:38:24 INFO - PROCESS | 2305 | Values: 97.0 130.0 89.0 104.0 113.0 130.0 117.0 86.0 90.0 86.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:96.00 stddev:14.07 (14.7%) stddev-sans-first:11.62 09:38:24 INFO - PROCESS | 2305 | Values: 129.0 97.0 97.0 98.0 96.0 105.0 131.0 96.0 96.0 94.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#7] imaging-desaturate Cycles:10 Average:103.80 Median:108.00 stddev:16.01 (14.8%) stddev-sans-first:16.89 09:38:24 INFO - PROCESS | 2305 | Values: 99.0 105.0 99.0 107.0 140.0 100.0 114.0 100.0 98.0 76.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:58.50 stddev:3.31 (5.7%) stddev-sans-first:3.46 09:38:24 INFO - PROCESS | 2305 | Values: 57.0 59.0 56.0 56.0 57.0 55.0 61.0 66.0 58.0 61.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#9] json-stringify-tinderbox Cycles:10 Average:83.80 Median:71.50 stddev:15.68 (21.9%) stddev-sans-first:15.25 09:38:24 INFO - PROCESS | 2305 | Values: 66.0 68.0 72.0 75.0 71.0 91.0 108.0 101.0 102.0 84.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:86.50 stddev:6.31 (7.3%) stddev-sans-first:6.69 09:38:24 INFO - PROCESS | 2305 | Values: 88.0 85.0 85.0 85.0 88.0 87.0 86.0 86.0 88.0 106.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#11] stanford-crypto-ccm Cycles:10 Average:134.60 Median:143.50 stddev:13.18 (9.2%) stddev-sans-first:13.41 09:38:24 INFO - PROCESS | 2305 | Values: 124.0 129.0 148.0 115.0 148.0 116.0 146.0 141.0 132.0 147.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.10 Median:143.50 stddev:9.15 (6.4%) stddev-sans-first:9.43 09:38:24 INFO - PROCESS | 2305 | Values: 141.0 154.0 142.0 142.0 143.0 160.0 141.0 144.0 165.0 139.0 09:38:24 INFO - PROCESS | 2305 | 09:38:24 INFO - PROCESS | 2305 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:68.00 stddev:3.69 (5.4%) stddev-sans-first:3.69 09:38:24 INFO - PROCESS | 2305 | Values: 70.0 68.0 69.0 67.0 70.0 68.0 58.0 63.0 65.0 67.0 09:38:24 INFO - PROCESS | 2305 | -------- Summary: end -------- 09:38:24 INFO - PROCESS | 2305 | 09:38:25 INFO - TEST-INFO | 2305: exit 0 09:38:25 INFO - TEST-OK | kraken | took 59369ms 09:38:25 INFO - SUITE-END | took 1170s 09:38:25 INFO - Completed test suite (00:19:31) 09:38:25 INFO - javascript benchmark 09:38:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3108.6269647642284, 3473.836264851275, 3482.7736197147183, 3550.2012047289345, 3560.9287814243717, 5779.971100144498, 5820.476157145856, 5832.212651292074, 5854.853628659275, 5965.284165900092, 5536.557075433966, 5656.2929633795775, 5711.029125048742, 5838.2702162229725, 5931.436513531207, 5160.74196290185, 5195.220716892459, 5241.9213711794355, 5253.474652534755, 5293.9735301323435, 5253.185756207775, 5268.9473105269035, 5281.287026251464, 5302.549283310923, 5307.708076055816, 5150.459201783815, 5198.636095473318, 5241.606879484054, 5248.422673505911, 5269.657472264291, 5221.06020916235, 5251.343582052244, 5265.420803711589, 5267.894642107175, 5310.176922577007, 5051.3433253676785, 5175.922361164586, 5203.895922081575, 5240.161574148118, 5247.816326428557, 5658.405867383948, 5688.374278829326, 5697.088465845445, 5718.399568045378, 5735.082386818089, 6131.3868613138775, 6176.69116544171, 6186.876262474725, 6189.071639254103, 6230.034344676583, 6115.48018418435, 6143.757528502109, 6198.752049917998, 6242.719077641472, 6245.313015568284, 5985.970070149621, 5989.9101513477335, 6005.069214271751, 6009.429104235091, 6010.2186715726675, 6303.936960630335, 6341.682915854189, 6348.238211414659, 6366.0, 6370.458511026522, 6101.877962440726, 6136.73353236607, 6155.630662160284, 6168.013117901114, 6198.977168767193, 6120.846978825565, 6186.350433204539, 6188.628682279078, 6219.844503887439, 6243.0635404688755, 6122.785702500391, 6149.631022138686, 6216.7824126155365, 6225.564210505221, 6240.500759939194, 5217.634765566374, 6108.736706092798, 6119.593952096689, 6176.536759743038, 6234.812955611339, 4657.441107067174, 4901.1913034349645, 4922.138625740481, 4936.7284799336385, 4970.17992031318, 4438.7114837535455, 4448.733076015449, 4463.977680111579, 4464.799084041226, 4508.436445444319, 5634.352049514277, 5795.681237531892, 5865.472107510344, 5887.735051922674, 5907.556933230025, 5506.311711036121, 5856.238688970407, 6198.194234749544, 6227.408396202353, 6234.501239900797, 5854.0, 5858.150568167592, 5858.707064646751, 5860.648361098348, 5861.3552509223955, 5302.337207849019, 5390.272313237741, 5405.594580406485, 5418.945810541844, 5419.376771671229, 6214.782482613087, 6298.811035668938, 6301.684915754194, 6348.269948955744, 6376.840578985562, 5919.970400147971, 6209.96895015522, 6221.0, 6243.125962365363, 6246.937530624636, 5230.7384630769075, 6305.306416294112, 6379.712912918837, 6386.87226255463, 6393.041043843275, 6116.701002199415, 6121.331035925228, 6185.474234689922, 6193.907091393543, 6199.3490683477585, 6160.845978850564, 6196.752129914753, 6229.159063526366, 6256.468200202851, 6277.811665650038, 4945.851624451272, 6002.339742628395, 6102.0651310563235, 6184.350643182581, 6194.4115309045565, 6112.755489780359, 6146.877062458815, 6148.1699970503405, 6167.229096362955, 6170.444659980623, 5006.849794506171, 5863.970680146743, 5927.644341339534, 6092.116643086639, 6107.083937409424, 6022.698865056642, 6120.143179954721, 6143.573288551087, 6144.402455361549, 6155.753769849156, 6012.218411606463, 6022.36765139654, 6040.577159598786, 6040.6375617461335, 6073.757049717962], "unit": "score", "name": "dojo.html", "value": 5705.091427930684}, {"lowerIsBetter": false, "replicates": [413.95032596088475, 479.5396419437341, 588.1618693361958, 622.5299898576574, 633.3792882974685, 37016.629833701685, 38075.667351642696, 38131.0, 38193.0, 38309.42535861964, 39476.21047579047, 39938.0, 40060.39909401354, 40061.39907901377, 40080.198396032065, 9271.907280927206, 9492.098250666175, 9510.668506409109, 9514.286428517855, 9544.236461083097, 9308.394954327981, 9511.239100871948, 9521.523923803818, 9522.285828562852, 9549.665761698307, 9491.477968711719, 9496.715098547054, 9508.904910950905, 9537.523123843815, 9582.041795820433, 9436.528173591327, 9478.241740660731, 9505.049495050474, 9521.571529281164, 9524.285678574135, 9351.064893510662, 9421.717348479557, 9501.2874034447, 9527.142557169818, 9558.665446709401, 38205.0, 38212.80893595542, 38470.80764596159, 38724.80637596822, 39029.41455878164, 3093.8607762650736, 3099.612548431446, 3129.7339726123305, 3200.2639392939523, 3372.089535825338, 28183.0, 28401.147965561067, 28686.569701454497, 28960.710392896224, 29150.0, 2731.644886164806, 2751.8073734838567, 2784.1090850927712, 2827.4910516107093, 2857.0714517781803, 490120.0, 495558.0, 501317.49341253063, 501941.99999999633, 502646.0, 67922.3207767916, 68691.31308686949, 68809.0, 68813.0, 68929.0, 74353.25646743464, 75238.62380688061, 75909.62045189849, 75930.62034689791, 75939.0, 16667.0, 16678.0, 17051.573710657332, 17149.657006859794, 17300.048497332515, 11483.655490335303, 12378.133530652947, 12870.806937895939, 12982.545610903762, 13178.670533236746, 21499.0650841425, 21596.568068638855, 21650.133994640037, 21683.891580541997, 22286.88856555707, 7939.325157361689, 8959.820803583892, 8999.37004409685, 9196.540172991323, 9228.538573071319, 928.3594319919234, 1003.4832061488339, 1032.5714828346302, 1119.7604790419161, 1170.2615878843378, 23793.52412951731, 30393.24016899595, 30960.690393096233, 31191.064268071994, 31247.375052498825, 942.3074040580168, 955.3408148377597, 1032.1536340201112, 1100.790849738563, 1110.722319420145, 12909.160904541362, 14521.636959075897, 14714.926425367805, 14967.176805275596, 15102.320395582225, 636162.0, 658893.0, 660596.0, 661870.0, 663196.0, 60597.0, 61115.38884611097, 61338.38661613416, 61426.38573614296, 61458.69270653708, 1417.8369487508862, 1432.1621851217155, 1459.7153555056875, 1469.5885152157414, 1471.0732238689557, 30332.545011824397, 30906.845465772527, 30985.53521697131, 30994.690053100083, 31615.683843162195, 49750.0, 49984.00031999267, 50223.74888125536, 50274.0, 50449.747751261006, 373802.0, 374189.0, 374260.0, 380175.0, 380538.0, 33781.324373512885, 43656.78171609249, 44295.335569965835, 44928.77535612301, 45412.54587454083, 43885.902852428946, 44152.0, 44209.55790442183, 44234.11531769282, 44309.33535997027, 35955.82022089873, 36073.0981725459, 36194.91415257547, 36809.263814724094, 37224.62775372212, 35771.71936032106, 37052.62947370492, 37343.81328093342, 37565.624343756215, 38154.0461488465, 35222.47166292559, 37054.8147259262, 37254.627453725116, 37255.44116838304, 39073.609263907, 26568.202953911412, 30974.845125774227, 31843.0, 32040.198995025312, 32184.0, 23808.285751427484, 24592.139275125286, 24681.136160235023, 26788.0, 30370.088897333117], "unit": "score", "name": "ext.html", "value": 20077.19385551246}, {"lowerIsBetter": false, "replicates": [353.4715600177733, 360.0422875152095, 365.90669379308275, 367.13539614208537, 370.0803503294315, 19048.809511904878, 19353.612927741455, 20462.488437789063, 20466.488337791525, 20518.589628207446, 11603.303801771899, 19192.328268510606, 19198.52003699908, 19225.134868930898, 19626.018699065025, 5092.108880945838, 5233.2150177473495, 5277.70972596507, 5280.762365693534, 5293.100172970604, 3910.061585219555, 5210.713410762407, 5668.149777533362, 5898.439648233411, 5948.643081415107, 4999.7500124993785, 5731.111677689964, 5844.26946631671, 5910.527157827363, 5921.378255283198, 5378.91931621026, 5636.605437619368, 5643.238162848004, 5654.406287339812, 5878.88242235155, 5315.202719592054, 5317.361916569997, 5419.783208671648, 5425.430329815392, 5425.9186112208345, 12563.994880409637, 12723.491060357575, 12758.298293593849, 12831.74336513274, 13326.133801302982, 1871.2421469304954, 1910.121344181678, 2055.619710353589, 2176.5320456101854, 2209.2930262316067, 9657.082577155159, 9790.5594248259, 9967.601295948154, 12706.110572259946, 14143.29283535819, 1612.8629066529359, 1642.786437763083, 1681.2854536821924, 1698.9745153822703, 1728.2222999650207, 90183.54908225418, 91824.54087729585, 92908.0, 92979.60530592047, 93733.0, 9131.223845973167, 9758.755486238206, 11149.275297105662, 11151.888481115086, 11158.776824463464, 13615.591532254048, 13651.795223071662, 13678.811390177523, 13688.0, 13690.178589284675, 8293.626786794608, 8501.107383724744, 8516.44643098209, 8528.914710852936, 8581.65673373058, 8532.104129066483, 8618.482891026559, 8664.913350866536, 8703.0, 8708.08565100668, 4379.5401482843845, 4400.163968845909, 4409.294512877924, 4415.66882483815, 4451.933221001687, 1746.4847869878417, 1825.178669598686, 1832.7342535332302, 1856.9442916712521, 1869.2336142181641, 851.5571902610608, 942.6182396129578, 978.9167920726818, 991.940483570988, 1022.9232807539464, 19319.4204173875, 19397.32109376165, 20002.799972000095, 20488.897555512423, 20512.0, 942.29328003997, 964.1178321835442, 995.144176008632, 1025.4308858583559, 1073.5544748929262, 10855.457227138611, 10984.06635435981, 11008.559657613605, 11019.504122314514, 11076.723081923017, 583139.0, 586677.0, 589649.0, 590230.0, 609628.0, 112514.43742781234, 114469.13827154221, 115473.0, 116086.0, 117652.6469470623, 1024.134606257711, 1036.8392899100652, 1037.3931250218707, 1082.815921293382, 1084.4198353880793, 17891.0, 18185.0, 18209.45371638853, 18240.72638910444, 18384.172712227715, 46376.768116159205, 47490.76254618705, 47583.76208118937, 47778.28332575084, 47842.56472305836, 64897.02654460281, 64944.0, 68414.65792671005, 69428.6528567354, 76291.0, 7727.961360193163, 7834.569098699626, 7847.882281765892, 7960.562169080757, 8027.8795818063945, 10912.836307455553, 12452.750944980868, 12735.363231838557, 12795.93602032021, 12826.486940522274, 10581.31221470602, 10679.8932010682, 11106.0, 11178.496967636313, 11189.552417903193, 9665.951670241604, 9957.950210248902, 10494.0, 10911.290766100177, 11063.336199828036, 10825.729356766144, 11220.214584978972, 11265.17156985146, 11330.98021178098, 11456.255343402652, 7741.806454838674, 7793.337566306927, 7799.571023593757, 7941.960290198512, 8092.907457493163, 9511.791229400242, 10745.677629671123, 10812.35125892449, 10961.123110151328, 11060.391678457761], "unit": "score", "name": "jquery.html", "value": 8998.380761015704}, {"lowerIsBetter": false, "replicates": [1588.0709784775906, 1597.0178340320704, 1874.1004317927395, 1959.373000639795, 1973.9506512337198, 8047.0, 8726.650933962634, 8733.432326898755, 8939.195472407482, 8944.463332200072, 8686.652533898636, 8723.4329768565, 8723.91276087239, 8821.558922053888, 8897.866532002025, 2170.8263338932925, 2478.5042991401747, 2504.4365017870923, 2505.235903049569, 2506.1854897158405, 2074.4917495213695, 2451.534208500379, 2476.7399423060588, 2482.7020757509126, 2488.4401009772837, 2470.468849197422, 2473.010795681733, 2478.7397323281066, 2499.0, 2499.3751562109474, 2482.6524286599893, 2492.626106084084, 2493.5636263653787, 2495.338735235164, 2497.300755788382, 2456.3981824452944, 2482.0, 2488.8506689598503, 2491.9003239870385, 2511.1713134665697, 6740.0, 7882.132965373804, 8905.465672059698, 8946.821063578693, 8954.955225223897, 5386.084365657847, 5484.670919744828, 5502.532284755801, 5529.30883639545, 5549.972250138723, 3557.2351944332095, 3592.3533763922483, 3670.3576874046935, 3679.742418030739, 3681.7054635629083, 1007.627177944158, 1031.8606988056592, 1034.1468288661883, 1034.6482196053378, 1036.217655669972, 11786.88213117875, 12002.039836813121, 12027.93986030073, 12035.976941959943, 12041.39793010346, 4076.3477843545484, 4245.214635292481, 4882.5605695486865, 4974.1543937530705, 4977.850664480072, 8908.242799361997, 9726.492393678991, 10133.493325333704, 10133.543990520444, 10143.64497242593, 5394.595405344615, 5472.288602481652, 5508.669479831223, 5522.9447705523235, 5528.834134975877, 4174.352975288835, 4413.713108647928, 4885.755712214446, 5008.6744361616375, 5061.519155680205, 3887.9805600971813, 3922.6077392260545, 3994.8801535953967, 4001.239764444746, 4012.3179059559943, 1124.6344937895217, 1317.9143355682043, 1324.6489680234745, 1331.8202042724301, 1347.5014244729514, 1061.559452827068, 1072.0298130192189, 1073.822819234833, 1382.1292585670963, 1393.1849867827232, 8102.635381407851, 8246.628901699438, 8359.8328033439, 8390.538520381439, 8409.957950210332, 493.75312343828085, 495.9851204463872, 496.5729472653515, 498.4765995704496, 519.3456245131161, 6069.514438844882, 6150.50795936324, 6178.69106544671, 6223.96888015566, 6227.688615569204, 10364.844527332096, 10639.840402393971, 10642.361458312525, 10676.0925321347, 10692.732681683021, 7025.964870175617, 10500.684979450629, 10565.0, 10577.524011419504, 10801.189910756568, 8299.709510167115, 8358.164183581715, 8414.915850841535, 8427.705030324032, 8459.49243045407, 8082.595870206349, 8096.190380961857, 8147.063087744985, 8182.7545173644885, 8219.342452603896, 9401.905980940102, 9631.325807193429, 9713.514324283899, 9755.56099975517, 9755.853662194932, 9871.555779989774, 9878.654247101604, 9912.684395134436, 10049.497525123568, 10120.190384769348, 6811.421029212573, 7225.024621676217, 7464.77605671809, 7564.810879728051, 7639.312461878458, 7533.3973282138395, 8111.513309201467, 8187.5087494750505, 8216.21945915137, 8271.296939760188, 6644.734210631714, 7265.346118849329, 7297.233790451926, 7358.1538123116525, 7463.253674632493, 6006.33930267679, 6150.415710507495, 6405.711742971671, 7193.424526037799, 7225.5664660120565, 6403.519736019724, 7465.0, 7682.154962953959, 7684.46208765381, 7717.691292348468, 5965.28416590012, 6824.078749368771, 6950.930490695028, 6973.232944376216, 7029.191642961125, 6899.275576064641, 7015.298470152944, 7062.1878483975, 7107.822304442224, 7120.287971202838], "unit": "score", "name": "mootools.html", "value": 4791.968201514045}, {"lowerIsBetter": false, "replicates": [327.3142765905428, 329.0047605991874, 334.8526648274759, 339.9919239455015, 340.7120982769559, 5603.60774745768, 6854.177498700156, 6891.34532219439, 6899.724011039559, 6902.068220790192, 6846.760363387282, 6849.589024658524, 6851.177858656968, 6872.381485666289, 6878.1058462399815, 4753.4533528644215, 4815.446223684286, 4936.185529387647, 4971.105201063807, 4973.0, 4915.5330243626795, 4953.0589188054155, 4955.628327875405, 4961.231009193581, 4964.67729597575, 4892.6575139740235, 4925.039617274633, 4940.011997600476, 4967.7019378837385, 4986.725730084844, 4967.403911530621, 4980.352554167935, 4981.302617633516, 4993.5755460785695, 5022.472640372781, 4279.186954478639, 4288.142371525676, 4299.591538803808, 4300.591443812833, 4300.6344460720875, 6310.1481300024825, 6516.1203237562795, 6522.902156467657, 6559.34406559345, 6614.470842332602, 5853.209816674779, 5896.823095307148, 5929.288485381739, 5936.910946335765, 5945.851353716192, 5837.970810145965, 7270.127584689871, 7274.272572742736, 7293.416526677906, 7329.19378868324, 4801.0, 4808.855734327975, 4813.66304358695, 4817.156997525454, 4838.346823178897, 6930.3762661360215, 7724.188960159215, 7730.884036739453, 7736.613169341566, 7770.184130666312, 4763.095011947719, 4821.855344339676, 4844.636652251095, 4849.587785038311, 4874.46380898101, 4925.965547235049, 4930.2851086592245, 4940.283658869444, 4943.876403089952, 4944.851654450372, 5826.82519524415, 5844.386339434298, 5851.239338886003, 5856.12158176277, 5867.354590994987, 7246.0, 7310.159331676925, 7320.926790732131, 7322.816929576697, 7336.7432139874845, 1636.7790348302826, 1677.9077150756825, 1694.6017685843817, 1701.7277235642239, 1719.5872990482194, 1097.8133717268083, 1103.8951299626522, 1116.5422176907591, 1117.7261570915177, 1126.048489026771, 5428.647137936022, 6688.4649228061635, 6713.395794378529, 6726.529142959946, 6729.360710732571, 7546.773596792105, 7565.167831538526, 7567.167611562723, 7583.810404739926, 7597.278258565428, 6665.600063996176, 6673.232578253563, 6698.5980841149685, 6701.731930722716, 6718.227403848522, 2960.860068873499, 3601.2977469393218, 3993.740406873544, 4016.5380981186954, 4019.7789121598594, 6118.479929205978, 6534.248561415403, 6791.898121528181, 7990.64042118106, 7992.200779922077, 6841.281665425058, 7721.613919304013, 7937.2459616336355, 7946.245106714853, 7966.681332746741, 7931.603419828985, 7954.045514538292, 7974.162712915177, 8000.15998320168, 8013.4791238569305, 6152.600080994721, 7320.890186647098, 7627.351675107678, 7665.69337226527, 7674.462787605037, 7360.926390736024, 7424.0, 7450.143233528213, 7451.888221676788, 7464.664090115849, 6353.4599559038, 6415.839604009937, 7875.527468351917, 7878.9212107880485, 7954.602269886598, 4652.185867473247, 4727.952720472751, 4729.527047295243, 4733.290006499053, 4733.479317275028, 5490.862728431821, 7392.186859445349, 7632.160462349026, 7653.196414376633, 7669.6548655309525, 7348.301911318416, 7387.1874093848555, 7409.62951852416, 7426.443016773872, 7430.888536671846, 6051.5461340400525, 7270.927290727025, 7354.301341372561, 7384.040074790243, 7387.187409385071, 7192.568445893263, 7197.244289349543, 7213.2065472799, 7218.747343842941, 7231.096112985877, 7129.857402852018, 7296.1974182838785, 7379.483436159418, 7386.704531818882, 7386.926130738624, 6301.0, 7111.004459375588, 7207.495475316888, 7218.169910460364, 7239.674214660247], "unit": "score", "name": "prototype.html", "value": 5275.821181688765}, {"lowerIsBetter": false, "replicates": [645.22572912505, 665.5707068940534, 705.2752009588571, 714.9678264478098, 752.8456666383388, 12211.755764884698, 12583.056270779694, 13914.51299204528, 14577.854221457783, 14699.265036748173, 11865.52537898483, 13329.333533323343, 13570.525031623894, 13605.319734013308, 13811.309434528284, 746.5073051785809, 880.0978996528578, 896.9730908072768, 907.9727608171765, 908.4413085952158, 870.0, 870.582120582121, 872.2498651159997, 892.2148509311796, 894.4588523943019, 943.4386540008718, 951.8286708392486, 952.7903861150536, 954.2985905359556, 956.2541217850088, 779.0, 912.7535565397338, 913.8720579118929, 938.713692323841, 948.0803620488116, 679.0934102972489, 695.4366962760181, 699.7725739134751, 704.4505285877024, 711.7153138744492, 13020.088593798439, 13072.215667060007, 13182.73634527304, 13203.735925281537, 13246.40391182399, 8919.197272245465, 8928.642854285821, 9366.250699943988, 10453.372797632166, 10985.780284394346, 9151.450912945245, 9202.0, 9318.673846415411, 9332.39339442934, 9365.859512107323, 3341.599008119017, 3402.2855200407944, 3416.7437442191685, 3471.6354782747953, 3489.494023366623, 77774.22225777726, 77995.61002194953, 78467.6076619619, 81917.0, 82247.35505289861, 6851.48613853963, 7593.848123037508, 8138.796530086795, 8165.469244499089, 8207.630656620466, 18721.0, 18956.905215473835, 18999.62000759977, 19001.049947502568, 19034.143463544173, 7221.494495385377, 7316.378107860892, 7330.230325815819, 7340.743073992385, 7370.189279179286, 3968.523777146761, 4005.859794907164, 4019.256437559061, 4025.959740402617, 4040.0303927057294, 3498.107982464506, 3500.7724497907557, 3567.0725611341236, 3567.9108022299133, 3590.9102272443397, 622.3682961793783, 633.5343522510951, 635.5138319185827, 646.0438550944629, 646.877093352271, 489.1183518193749, 524.3655177235512, 525.7108590275333, 580.1298052920619, 583.8306891001647, 6577.506686998398, 8051.0, 8173.060098088677, 8267.379946504036, 8308.376871734645, 524.5960610330101, 551.0486743173788, 578.5227187570272, 595.928488581373, 599.6252342286072, 3057.5260834570677, 3722.274156539449, 3776.792276424823, 3799.981000094982, 3887.1642596841816, 85750.57124714337, 86739.0, 87930.0, 89012.5549372249, 91196.54401727949, 77220.0, 77500.0, 77880.83178752323, 78260.2173978253, 79416.6029169862, 70405.64797175981, 71092.93360599595, 71336.64331678412, 72664.0, 73861.26138738652, 7658.578778167254, 7726.111497177896, 7727.111382190895, 7745.883811742716, 7824.935674852035, 49756.0, 50926.490735093656, 52216.73891630518, 52281.7385913068, 53550.0, 58159.70920145372, 59794.402055978884, 65341.346586533524, 68305.0, 71875.64062179656, 9451.858222126537, 10615.89384106149, 10628.734281643021, 10639.89360106389, 11042.447877606248, 11579.363135027317, 11624.535018599161, 11670.299782013106, 11670.69540723559, 12068.39658017078, 9557.904420955701, 11162.441877906234, 11269.295369111518, 11294.774104518028, 11557.88442115568, 11363.034142098015, 11387.373694446553, 11493.540258389905, 11832.585859494877, 11952.880471195176, 11164.665060048212, 11239.213255072067, 11558.595449159575, 11651.825222621837, 11732.12009099338, 8985.607230879225, 9220.14865728881, 9387.15515603599, 9392.201662858733, 9845.556949937127, 7894.842103158019, 9540.475273860004, 9596.136347728738, 9646.758831029281, 9672.22622190209], "unit": "score", "name": "yui.html", "value": 6602.145906616398}], "name": "dromaeo_css", "value": 7457.771496042495}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [132.0, 156.0, 161.0, 116.0, 116.0, 129.0, 115.0, 118.0, 115.0, 115.0], "unit": "score", "name": "ai-astar", "value": 127.3}, {"lowerIsBetter": true, "replicates": [154.0, 143.0, 132.0, 176.0, 119.0, 168.0, 149.0, 153.0, 195.0, 117.0], "unit": "score", "name": "audio-beat-detection", "value": 150.6}, {"lowerIsBetter": true, "replicates": [201.0, 199.0, 198.0, 209.0, 222.0, 248.0, 272.0, 202.0, 213.0, 247.0], "unit": "score", "name": "audio-dft", "value": 221.1}, {"lowerIsBetter": true, "replicates": [73.0, 82.0, 84.0, 154.0, 87.0, 48.0, 65.0, 91.0, 158.0, 75.0], "unit": "score", "name": "audio-fft", "value": 91.7}, {"lowerIsBetter": true, "replicates": [69.0, 122.0, 169.0, 77.0, 80.0, 86.0, 148.0, 116.0, 119.0, 105.0], "unit": "score", "name": "audio-oscillator", "value": 109.1}, {"lowerIsBetter": true, "replicates": [97.0, 130.0, 89.0, 104.0, 113.0, 130.0, 117.0, 86.0, 90.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 104.2}, {"lowerIsBetter": true, "replicates": [129.0, 97.0, 97.0, 98.0, 96.0, 105.0, 131.0, 96.0, 96.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 103.9}, {"lowerIsBetter": true, "replicates": [99.0, 105.0, 99.0, 107.0, 140.0, 100.0, 114.0, 100.0, 98.0, 76.0], "unit": "score", "name": "imaging-desaturate", "value": 103.8}, {"lowerIsBetter": true, "replicates": [57.0, 59.0, 56.0, 56.0, 57.0, 55.0, 61.0, 66.0, 58.0, 61.0], "unit": "score", "name": "json-parse-financial", "value": 58.6}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 72.0, 75.0, 71.0, 91.0, 108.0, 101.0, 102.0, 84.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 83.8}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 85.0, 85.0, 88.0, 87.0, 86.0, 86.0, 88.0, 106.0], "unit": "score", "name": "stanford-crypto-aes", "value": 88.4}, {"lowerIsBetter": true, "replicates": [124.0, 129.0, 148.0, 115.0, 148.0, 116.0, 146.0, 141.0, 132.0, 147.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 134.6}, {"lowerIsBetter": true, "replicates": [141.0, 154.0, 142.0, 142.0, 143.0, 160.0, 141.0, 144.0, 165.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 147.1}, {"lowerIsBetter": true, "replicates": [70.0, 68.0, 69.0, 67.0, 70.0, 68.0, 58.0, 63.0, 65.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.5}], "name": "kraken", "value": 1590.6999999999998}]} 09:38:25 INFO - Return code: 0 09:38:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:38:25 ERROR - # TBPL SUCCESS # 09:38:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:25 INFO - 2016-04-07 09:38:25.978 screenresolution[2310:23918] starting screenresolution argv=screenresolution get 09:38:25 INFO - 2016-04-07 09:38:25.981 screenresolution[2310:23918] Display 0: 1600x1200x32@60 09:38:25 INFO - 2016-04-07 09:38:25.987 screenresolution[2311:23921] starting screenresolution argv=screenresolution list 09:38:25 INFO - Available Modes on Display 0 09:38:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:26 INFO - Intel Iris: 09:38:26 INFO - Chipset Model: Intel Iris 09:38:26 INFO - Type: GPU 09:38:26 INFO - Bus: Built-In 09:38:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:26 INFO - Vendor: Intel (0x8086) 09:38:26 INFO - Device ID: 0x0a2e 09:38:26 INFO - Revision ID: 0x0009 09:38:26 INFO - Displays: 09:38:26 INFO - Display: 09:38:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:26 INFO - Display Serial Number: 1600x1200 60 09:38:26 INFO - Main Display: Yes 09:38:26 INFO - Mirror: Off 09:38:26 INFO - Online: Yes 09:38:26 INFO - Rotation: Supported 09:38:26 INFO - Return code: 0 09:38:26 INFO - Running post-action listener: _resource_record_post_action 09:38:26 INFO - Running post-run listener: _resource_record_post_run 09:38:27 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 233472; Write bytes: 694576640; Read time: 3; Write time: 2059 09:38:27 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 385860096; Write bytes: 379573760; Read time: 28330; Write time: 703 09:38:27 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 233472; Write bytes: 298586112; Read time: 3; Write time: 1317 09:38:27 INFO - Running post-run listener: _upload_blobber_files 09:38:27 INFO - Blob upload gear active. 09:38:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:38:27 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:38:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:38:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:28 INFO - (blobuploader) - INFO - Done attempting. 09:38:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:38:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:29 INFO - (blobuploader) - INFO - Done attempting. 09:38:29 INFO - (blobuploader) - INFO - Iteration through files over. 09:38:29 INFO - Return code: 0 09:38:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:38:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:38:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6746fa4bfe7d65962a54a530900caa564d4b4c816f1f32a8354e03a0d69bf231250c7364f9043a1b77ddc7b4297f0399cb8b8dc750287fbd38e4e9314e07f709", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaddf2c58c224e898bd821e1f97957e54dd9f806ccd94eb463183e08c1765b071e248ef29c50d123892c48e066aa29abff39c8802db8e73588d61b8554030efd"} 09:38:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:38:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:38:29 INFO - Contents: 09:38:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6746fa4bfe7d65962a54a530900caa564d4b4c816f1f32a8354e03a0d69bf231250c7364f9043a1b77ddc7b4297f0399cb8b8dc750287fbd38e4e9314e07f709", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaddf2c58c224e898bd821e1f97957e54dd9f806ccd94eb463183e08c1765b071e248ef29c50d123892c48e066aa29abff39c8802db8e73588d61b8554030efd"} 09:38:29 INFO - Running post-run listener: copy_logs_to_upload_dir 09:38:29 INFO - Copying logs to upload dir... 09:38:29 INFO - mkdir: /builds/slave/test/build/upload/logs 09:38:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.513167 ========= master_lag: 1.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-07 09:38:30.798393) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2016-04-07 09:38:30.802273) ========= 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.R3FckehF7H/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.htDqw2przm/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6746fa4bfe7d65962a54a530900caa564d4b4c816f1f32a8354e03a0d69bf231250c7364f9043a1b77ddc7b4297f0399cb8b8dc750287fbd38e4e9314e07f709", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaddf2c58c224e898bd821e1f97957e54dd9f806ccd94eb463183e08c1765b071e248ef29c50d123892c48e066aa29abff39c8802db8e73588d61b8554030efd"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011483 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6746fa4bfe7d65962a54a530900caa564d4b4c816f1f32a8354e03a0d69bf231250c7364f9043a1b77ddc7b4297f0399cb8b8dc750287fbd38e4e9314e07f709", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaddf2c58c224e898bd821e1f97957e54dd9f806ccd94eb463183e08c1765b071e248ef29c50d123892c48e066aa29abff39c8802db8e73588d61b8554030efd"}' ========= master_lag: 6.78 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2016-04-07 09:38:37.591930) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:38:37.592278) ========= 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.R3FckehF7H/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.htDqw2przm/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.004716 ========= master_lag: 0.48 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:38:38.076945) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-07 09:38:38.077262) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-07 09:38:42.050246) ========= ========= Total master_lag: 11.34 =========