builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0200 starttime: 1460045856.85 results: success (0) buildid: 20160407090711 builduid: e321e347a09e4e08959e5daf9d727dda revision: 55f0de142be46043246b18108299aebf2bd95a64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:36.850528) ========= 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:36.850953) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:36.851314) ========= 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.LvURJh4dmx/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.176pnwAAZL/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:36.883723) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:36.884061) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:36.901176) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:36.901483) ========= 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.LvURJh4dmx/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.176pnwAAZL/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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.005275 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:36.949743) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:36.950084) ========= 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:36.950490) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:36.950824) ========= 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.LvURJh4dmx/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.176pnwAAZL/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 175M=0s 2016-04-07 09:17:37 (175 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109948 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:37.081099) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:37.081420) ========= 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.LvURJh4dmx/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.176pnwAAZL/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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.020350 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:37.129786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-07 09:17:37.133182) ========= 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.LvURJh4dmx/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.176pnwAAZL/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:37,210 truncating revision to first 12 chars 2016-04-07 09:17:37,210 Setting DEBUG logging. 2016-04-07 09:17:37,210 attempt 1/10 2016-04-07 09:17:37,210 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:39,283 unpacking tar archive at: fx-team-55f0de142be4/testing/mozharness/ program finished with exit code 0 elapsedTime=2.312867 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-07 09:17:39.573259) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:39.573655) ========= 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.LvURJh4dmx/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.176pnwAAZL/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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.004932 script_repo_revision: '55f0de142be46043246b18108299aebf2bd95a64' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:39.631462) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:39.632237) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:39.760523) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-04-07 09:17:39.760967) ========= /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.LvURJh4dmx/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.176pnwAAZL/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:39 INFO - MultiFileLogger online at 20160407 09:17:39 in /builds/slave/test 09:17:39 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:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:39 INFO - {'append_to_log': False, 09:17:39 INFO - 'base_work_dir': '/builds/slave/test', 09:17:39 INFO - 'blob_upload_branch': 'Fx-Team', 09:17:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:39 INFO - 'branch': 'Fx-Team', 09:17:39 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:39 INFO - 'config_files': ('talos/mac_config.py',), 09:17:39 INFO - 'default_actions': ('clobber', 09:17:39 INFO - 'read-buildbot-config', 09:17:39 INFO - 'download-and-extract', 09:17:39 INFO - 'populate-webroot', 09:17:39 INFO - 'create-virtualenv', 09:17:39 INFO - 'install', 09:17:39 INFO - 'run-tests'), 09:17:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:39 INFO - 'download_minidump_stackwalk': True, 09:17:39 INFO - 'download_symbols': 'ondemand', 09:17:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:39 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:39 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:39 INFO - 'installer_path': 'installer.exe', 09:17:39 INFO - 'log_level': 'info', 09:17:39 INFO - 'log_name': 'talos', 09:17:39 INFO - 'log_to_console': True, 09:17:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:17:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:17:39 INFO - 'opt_config_files': (), 09:17:39 INFO - 'pip_index': False, 09:17:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:39 INFO - 'cmd': ('bash', 09:17:39 INFO - '-c', 09:17:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:39 INFO - 'enabled': True, 09:17:39 INFO - 'halt_on_failure': False, 09:17:39 INFO - 'name': 'check_screen_resolution'},), 09:17:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:39 INFO - 'cmd': ('bash', 09:17:39 INFO - '-c', 09:17:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:39 INFO - 'enabled': True, 09:17:39 INFO - 'halt_on_failure': False, 09:17:39 INFO - 'name': 'check_screen_resolution'},), 09:17:39 INFO - 'run_cmd_checks_enabled': True, 09:17:39 INFO - 'sps_profile': False, 09:17:39 INFO - 'sps_profile_interval': 0, 09:17:39 INFO - 'suite': 'dromaeojs-e10s', 09:17:39 INFO - 'system_bits': '32', 09:17:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:39 INFO - 'title': 't-yosemite-r7-0200', 09:17:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:39 INFO - 'use_talos_json': True, 09:17:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:39 INFO - 'work_dir': 'build'} 09:17:39 INFO - ##### 09:17:39 INFO - ##### Running clobber step. 09:17:39 INFO - ##### 09:17:39 INFO - Running pre-action listener: _resource_record_pre_action 09:17:39 INFO - Running main action method: clobber 09:17:39 INFO - rmtree: /builds/slave/test/build 09:17:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:40 INFO - Running post-action listener: _resource_record_post_action 09:17:40 INFO - ##### 09:17:40 INFO - ##### Running read-buildbot-config step. 09:17:40 INFO - ##### 09:17:40 INFO - Running pre-action listener: _resource_record_pre_action 09:17:40 INFO - Running main action method: read_buildbot_config 09:17:40 INFO - Using buildbot properties: 09:17:40 INFO - { 09:17:40 INFO - "project": "", 09:17:40 INFO - "product": "firefox", 09:17:40 INFO - "script_repo_revision": "production", 09:17:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:17:40 INFO - "repository": "", 09:17:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:17:40 INFO - "buildid": "20160407090711", 09:17:40 INFO - "stage_platform": "macosx64", 09:17:40 INFO - "basedir": "/builds/slave/test", 09:17:40 INFO - "buildnumber": 591, 09:17:40 INFO - "platform": "yosemite_r7", 09:17:40 INFO - "mozci_request": { 09:17:40 INFO - "priority": -1, 09:17:40 INFO - "type": "trigger_all_talos_jobs", 09:17:40 INFO - "times": 6 09:17:40 INFO - }, 09:17:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:17:40 INFO - "slavebuilddir": "test", 09:17:40 INFO - "branch": "fx-team", 09:17:40 INFO - "revision": "55f0de142be46043246b18108299aebf2bd95a64", 09:17:40 INFO - "slavename": "t-yosemite-r7-0200", 09:17:40 INFO - "builduid": "e321e347a09e4e08959e5daf9d727dda", 09:17:40 INFO - "repo_path": "integration/fx-team" 09:17:40 INFO - } 09:17:40 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:40 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:40 INFO - Running post-action listener: _resource_record_post_action 09:17:40 INFO - ##### 09:17:40 INFO - ##### Running download-and-extract step. 09:17:40 INFO - ##### 09:17:40 INFO - Running pre-action listener: _resource_record_pre_action 09:17:40 INFO - Running main action method: download_and_extract 09:17:40 INFO - mkdir: /builds/slave/test/build/tests 09:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:40 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:40 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:40 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:40 INFO - Downloaded 1199 bytes. 09:17:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:17:40 INFO - Using the following test package requirements: 09:17:40 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:17:40 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:40 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:17:40 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:40 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:17:40 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:40 INFO - u'jsshell-mac.zip'], 09:17:40 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:40 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:17:40 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:17:40 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:40 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:17:40 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:40 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:17:40 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:40 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:17:40 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:40 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:17:40 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:40 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:40 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:40 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:41 INFO - Downloaded 17970719 bytes. 09:17:41 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:41 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:44 INFO - Return code: 0 09:17:44 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:44 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:44 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:44 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:44 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:44 INFO - Downloaded 17970719 bytes. 09:17:44 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:44 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:48 INFO - Return code: 0 09:17:48 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:48 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:48 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:48 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:48 INFO - Downloaded 10847617 bytes. 09:17:48 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:48 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:48 INFO - Return code: 0 09:17:48 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:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg 09:17:48 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:48 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:50 INFO - Downloaded 108918781 bytes. 09:17:50 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:50 INFO - mkdir: /builds/slave/test/properties 09:17:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:50 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:50 INFO - Contents: 09:17:50 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg 09:17:50 INFO - Running post-action listener: _resource_record_post_action 09:17:50 INFO - Running post-action listener: set_extra_try_arguments 09:17:50 INFO - ##### 09:17:50 INFO - ##### Running populate-webroot step. 09:17:50 INFO - ##### 09:17:50 INFO - Running pre-action listener: _resource_record_pre_action 09:17:50 INFO - Running main action method: populate_webroot 09:17:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:50 INFO - '--tppagecycles', 09:17:50 INFO - '7'], 09:17:50 INFO - 'tests': ['tsvgm']}}, 09:17:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:50 INFO - 'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:50 INFO - 'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['damp', 'tps']}, 09:17:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:50 INFO - 'tests': ['damp', 'tps']}, 09:17:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['dromaeo_dom']}, 09:17:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['a11yr', 09:17:50 INFO - 'ts_paint', 09:17:50 INFO - 'tpaint', 09:17:50 INFO - 'sessionrestore', 09:17:50 INFO - 'sessionrestore_no_auto_restore', 09:17:50 INFO - 'tabpaint']}, 09:17:50 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:50 INFO - 'ts_paint', 09:17:50 INFO - 'tpaint', 09:17:50 INFO - 'sessionrestore', 09:17:50 INFO - 'sessionrestore_no_auto_restore', 09:17:50 INFO - 'tabpaint']}, 09:17:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:50 INFO - 'ts_paint', 09:17:50 INFO - 'tpaint', 09:17:50 INFO - 'sessionrestore', 09:17:50 INFO - 'sessionrestore_no_auto_restore', 09:17:50 INFO - 'tabpaint']}, 09:17:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:50 INFO - 'ts_paint', 09:17:50 INFO - 'tpaint', 09:17:50 INFO - 'sessionrestore', 09:17:50 INFO - 'sessionrestore_no_auto_restore', 09:17:50 INFO - 'tabpaint']}, 09:17:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['a11yr', 09:17:50 INFO - 'ts_paint', 09:17:50 INFO - 'tpaint', 09:17:50 INFO - 'sessionrestore', 09:17:50 INFO - 'sessionrestore_no_auto_restore', 09:17:50 INFO - 'tabpaint']}, 09:17:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['a11yr', 09:17:50 INFO - 'ts_paint', 09:17:50 INFO - 'tpaint', 09:17:50 INFO - 'sessionrestore', 09:17:50 INFO - 'sessionrestore_no_auto_restore', 09:17:50 INFO - 'tabpaint']}, 09:17:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['tsvgx', 09:17:50 INFO - 'tsvgr_opacity', 09:17:50 INFO - 'tart', 09:17:50 INFO - 'tscrollx', 09:17:50 INFO - 'cart']}, 09:17:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:50 INFO - 'tsvgr_opacity', 09:17:50 INFO - 'tart', 09:17:50 INFO - 'tscrollx', 09:17:50 INFO - 'cart']}, 09:17:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:50 INFO - 'talos_options': ['--disable-e10s'], 09:17:50 INFO - 'tests': ['tp5o']}, 09:17:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:50 INFO - 'tests': ['tp5o']}, 09:17:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:50 INFO - 'talos_options': ['--disable-e10s', 09:17:50 INFO - '--xperf_path', 09:17:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:50 INFO - 'tests': ['tp5n']}, 09:17:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:50 INFO - 'talos_options': ['--xperf_path', 09:17:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:50 INFO - 'tests': ['tp5n']}}, 09:17:50 INFO - 'talos.zip': {'path': '', 09:17:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:50 INFO - Running post-action listener: _resource_record_post_action 09:17:50 INFO - ##### 09:17:50 INFO - ##### Running create-virtualenv step. 09:17:50 INFO - ##### 09:17:50 INFO - Running pre-action listener: _resource_record_pre_action 09:17:50 INFO - Running main action method: create_virtualenv 09:17:50 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:50 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:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:50 INFO - Using real prefix '/tools/python27' 09:17:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:54 INFO - Installing pip.................done. 09:17:54 INFO - Return code: 0 09:17:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:54 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:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:54 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:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:54 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 0x1104c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104d7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23468500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, '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.LvURJh4dmx/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.176pnwAAZL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:54 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:54 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:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/Render', 09:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:54 INFO - 'HOME': '/Users/cltbld', 09:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:54 INFO - 'LOGNAME': 'cltbld', 09:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:54 INFO - 'MOZ_NO_REMOTE': '1', 09:17:54 INFO - 'NO_EM_RESTART': '1', 09:17:54 INFO - 'PAGER': '/bin/cat', 09:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:54 INFO - 'PWD': '/builds/slave/test', 09:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:54 INFO - 'SHELL': '/bin/bash', 09:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.176pnwAAZL/Listeners', 09:17:54 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:54 INFO - 'USER': 'cltbld', 09:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:54 INFO - 'XPC_FLAGS': '0x0', 09:17:54 INFO - 'XPC_SERVICE_NAME': '0', 09:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:54 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:54 INFO - Cleaning up... 09:17:54 INFO - Return code: 0 09:17:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:17:54 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:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:54 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:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:54 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 0x1104c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104d7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23468500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, '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.LvURJh4dmx/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.176pnwAAZL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:54 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:54 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:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/Render', 09:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:54 INFO - 'HOME': '/Users/cltbld', 09:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:54 INFO - 'LOGNAME': 'cltbld', 09:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:54 INFO - 'MOZ_NO_REMOTE': '1', 09:17:54 INFO - 'NO_EM_RESTART': '1', 09:17:54 INFO - 'PAGER': '/bin/cat', 09:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:54 INFO - 'PWD': '/builds/slave/test', 09:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:54 INFO - 'SHELL': '/bin/bash', 09:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.176pnwAAZL/Listeners', 09:17:54 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:54 INFO - 'USER': 'cltbld', 09:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:54 INFO - 'XPC_FLAGS': '0x0', 09:17:54 INFO - 'XPC_SERVICE_NAME': '0', 09:17:54 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 psutil>=0.7.1 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:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:57 INFO - Installing collected packages: psutil 09:17:57 INFO - Running setup.py install for psutil 09:17:57 INFO - building 'psutil._psutil_osx' extension 09:17:57 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:57 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:57 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:57 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:57 INFO - building 'psutil._psutil_posix' extension 09:17:57 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:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:17:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:17:57 INFO - ^ 09:17:57 INFO - 1 warning generated. 09:17:57 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:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:58 INFO - Successfully installed psutil 09:17:58 INFO - Cleaning up... 09:17:58 INFO - Return code: 0 09:17:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:58 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 0x1104c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104d7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23468500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, '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.LvURJh4dmx/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.176pnwAAZL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:58 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:58 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:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/Render', 09:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:58 INFO - 'HOME': '/Users/cltbld', 09:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:58 INFO - 'LOGNAME': 'cltbld', 09:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:58 INFO - 'MOZ_NO_REMOTE': '1', 09:17:58 INFO - 'NO_EM_RESTART': '1', 09:17:58 INFO - 'PAGER': '/bin/cat', 09:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:58 INFO - 'PWD': '/builds/slave/test', 09:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:58 INFO - 'SHELL': '/bin/bash', 09:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.176pnwAAZL/Listeners', 09:17:58 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:58 INFO - 'USER': 'cltbld', 09:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:58 INFO - 'XPC_FLAGS': '0x0', 09:17:58 INFO - 'XPC_SERVICE_NAME': '0', 09:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:17:58 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:58 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:58 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:58 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:58 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:58 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 mozsystemmonitor-0.0.tar.gz 09:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:18:00 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:18:00 INFO - Installing collected packages: mozsystemmonitor 09:18:00 INFO - Running setup.py install for mozsystemmonitor 09:18:01 INFO - Successfully installed mozsystemmonitor 09:18:01 INFO - Cleaning up... 09:18:01 INFO - Return code: 0 09:18:01 INFO - Installing blobuploader==1.2.4 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', '--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 0x1104c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104d7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23468500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, '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.LvURJh4dmx/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.176pnwAAZL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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', '--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:18:01 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:18:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/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.176pnwAAZL/Listeners', 09:18:01 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:01 INFO - Downloading/unpacking blobuploader==1.2.4 09:18:01 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:01 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:01 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:01 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:01 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:01 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:03 INFO - Downloading blobuploader-1.2.4.tar.gz 09:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:18:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:18:03 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:03 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:03 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:03 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:03 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:03 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:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:18:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18:04 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:04 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:04 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:04 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:04 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:04 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:04 INFO - Downloading docopt-0.6.1.tar.gz 09:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:05 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:05 INFO - Running setup.py install for blobuploader 09:18:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:18:05 INFO - Running setup.py install for requests 09:18:05 INFO - Running setup.py install for docopt 09:18:05 INFO - Successfully installed blobuploader requests docopt 09:18:05 INFO - Cleaning up... 09:18:05 INFO - Return code: 0 09:18:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:05 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:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:05 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:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:05 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 0x1104c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104d7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23468500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, '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.LvURJh4dmx/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.176pnwAAZL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:05 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:05 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:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/Render', 09:18:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:05 INFO - 'HOME': '/Users/cltbld', 09:18:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:05 INFO - 'LOGNAME': 'cltbld', 09:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:05 INFO - 'MOZ_NO_REMOTE': '1', 09:18:05 INFO - 'NO_EM_RESTART': '1', 09:18:05 INFO - 'PAGER': '/bin/cat', 09:18:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:05 INFO - 'PWD': '/builds/slave/test', 09:18:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:05 INFO - 'SHELL': '/bin/bash', 09:18:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.176pnwAAZL/Listeners', 09:18:05 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:18:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:05 INFO - 'USER': 'cltbld', 09:18:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:05 INFO - 'XPC_FLAGS': '0x0', 09:18:05 INFO - 'XPC_SERVICE_NAME': '0', 09:18:05 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/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-2N9rVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:06 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-0cJsY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:06 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-1bMKKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:06 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-bTUs3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:06 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-_AcGmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:06 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-0blucg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:06 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-bpCvyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:06 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-5m6OHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:06 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-jTxpDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:07 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-BgQmM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:07 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-NZDcf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:07 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-QypKYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:07 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-U6pMpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:07 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-dSibBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:07 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-srVDcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:07 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-Blb2KQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:07 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-CFqZpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:18:07 INFO - Running setup.py install for manifestparser 09:18:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:18:08 INFO - Running setup.py install for mozcrash 09:18:08 INFO - Running setup.py install for mozdebug 09:18:08 INFO - Running setup.py install for mozdevice 09:18:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:18:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:18:08 INFO - Running setup.py install for mozfile 09:18:08 INFO - Running setup.py install for mozhttpd 09:18:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:18:08 INFO - Running setup.py install for mozinfo 09:18:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:18:08 INFO - Running setup.py install for mozInstall 09:18:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:18:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:18:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:18:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:18:09 INFO - Running setup.py install for mozleak 09:18:09 INFO - Running setup.py install for mozlog 09:18:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:18:09 INFO - Running setup.py install for moznetwork 09:18:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:18:09 INFO - Running setup.py install for mozprocess 09:18:09 INFO - Running setup.py install for mozprofile 09:18:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:18:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:18:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:18:09 INFO - Running setup.py install for mozrunner 09:18:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:18:10 INFO - Running setup.py install for mozscreenshot 09:18:10 INFO - Running setup.py install for moztest 09:18:10 INFO - Running setup.py install for mozversion 09:18:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:18:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:18:10 INFO - Cleaning up... 09:18:10 INFO - Return code: 0 09:18:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:10 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 0x1104c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104d7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23468500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, '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.LvURJh4dmx/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.176pnwAAZL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:10 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:10 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:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/Render', 09:18:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:10 INFO - 'HOME': '/Users/cltbld', 09:18:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:10 INFO - 'LOGNAME': 'cltbld', 09:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:10 INFO - 'MOZ_NO_REMOTE': '1', 09:18:10 INFO - 'NO_EM_RESTART': '1', 09:18:10 INFO - 'PAGER': '/bin/cat', 09:18:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:10 INFO - 'PWD': '/builds/slave/test', 09:18:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:10 INFO - 'SHELL': '/bin/bash', 09:18:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.176pnwAAZL/Listeners', 09:18:10 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:18:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:10 INFO - 'USER': 'cltbld', 09:18:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:10 INFO - 'XPC_FLAGS': '0x0', 09:18:10 INFO - 'XPC_SERVICE_NAME': '0', 09:18:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:10 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-f_ul4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:10 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:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:10 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-r52ek2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:10 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:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:10 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-ipJhpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:11 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-_P48F3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:11 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-N2ImeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:11 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-_qtOka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:11 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-ynV_TQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:11 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-iHJjt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:11 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-LVmz0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:11 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-OtPBlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:11 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-7kGNDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:11 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:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:11 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-kvcabc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:12 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:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:12 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-OoiPcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:12 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:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:12 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-KfOJkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:12 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:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:12 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-7keXOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:12 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:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:12 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-TqBHu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:12 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:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:12 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-B7kkeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:12 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:12 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:12 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:12 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:12 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:12 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:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 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 blessings-1.6.tar.gz 09:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:18:15 INFO - Installing collected packages: blessings 09:18:15 INFO - Running setup.py install for blessings 09:18:15 INFO - Successfully installed blessings 09:18:15 INFO - Cleaning up... 09:18:15 INFO - Return code: 0 09:18:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:18:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:15 INFO - Reading from file tmpfile_stdout 09:18:15 INFO - Current package versions: 09:18:15 INFO - blessings == 1.6 09:18:15 INFO - blobuploader == 1.2.4 09:18:15 INFO - docopt == 0.6.1 09:18:15 INFO - manifestparser == 1.1 09:18:15 INFO - mozInstall == 1.12 09:18:15 INFO - mozcrash == 0.17 09:18:15 INFO - mozdebug == 0.1 09:18:15 INFO - mozdevice == 0.48 09:18:15 INFO - mozfile == 1.2 09:18:15 INFO - mozhttpd == 0.7 09:18:15 INFO - mozinfo == 0.9 09:18:15 INFO - mozleak == 0.1 09:18:15 INFO - mozlog == 3.1 09:18:15 INFO - moznetwork == 0.27 09:18:15 INFO - mozprocess == 0.22 09:18:15 INFO - mozprofile == 0.28 09:18:15 INFO - mozrunner == 6.11 09:18:15 INFO - mozscreenshot == 0.1 09:18:15 INFO - mozsystemmonitor == 0.0 09:18:15 INFO - moztest == 0.7 09:18:15 INFO - mozversion == 1.4 09:18:15 INFO - psutil == 3.1.1 09:18:15 INFO - requests == 1.2.3 09:18:15 INFO - wsgiref == 0.1.2 09:18:15 INFO - Installing None 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', '-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 0x1104c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104d7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23468500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, '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.LvURJh4dmx/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.176pnwAAZL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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', '-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:15 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:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/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.176pnwAAZL/Listeners', 09:18:15 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 - 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:15 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:15 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:15 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:15 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:15 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:15 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:15 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:15 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:15 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:15 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:15 INFO - Cleaning up... 09:18:15 INFO - Return code: 0 09:18:15 INFO - Installing jsonschema 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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1104c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104d7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23468500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, '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.LvURJh4dmx/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.176pnwAAZL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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', 'jsonschema'] 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 jsonschema 09:18:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/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.176pnwAAZL/Listeners', 09:18:15 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:16 INFO - Downloading/unpacking jsonschema 09:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:18 INFO - Downloading/unpacking functools32 (from jsonschema) 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 functools32-3.2.3-2.tar.gz 09:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:18 INFO - warning: no files found matching '*.txt' 09:18:18 INFO - no previously-included directories found matching 'build' 09:18:18 INFO - no previously-included directories found matching 'dist' 09:18:18 INFO - no previously-included directories found matching '.git*' 09:18:18 INFO - Installing collected packages: jsonschema, functools32 09:18:18 INFO - Running setup.py install for functools32 09:18:19 INFO - warning: no files found matching '*.txt' 09:18:19 INFO - no previously-included directories found matching 'build' 09:18:19 INFO - no previously-included directories found matching 'dist' 09:18:19 INFO - no previously-included directories found matching '.git*' 09:18:19 INFO - Successfully installed jsonschema functools32 09:18:19 INFO - Cleaning up... 09:18:19 INFO - Return code: 0 09:18:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:19 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 0x1104c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104d7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23468500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, '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.LvURJh4dmx/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.176pnwAAZL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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:19 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:19 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:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/Render', 09:18:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:19 INFO - 'HOME': '/Users/cltbld', 09:18:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:19 INFO - 'LOGNAME': 'cltbld', 09:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:19 INFO - 'MOZ_NO_REMOTE': '1', 09:18:19 INFO - 'NO_EM_RESTART': '1', 09:18:19 INFO - 'PAGER': '/bin/cat', 09:18:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:19 INFO - 'PWD': '/builds/slave/test', 09:18:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:19 INFO - 'SHELL': '/bin/bash', 09:18:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.176pnwAAZL/Listeners', 09:18:19 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:18:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:19 INFO - 'USER': 'cltbld', 09:18:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:19 INFO - 'XPC_FLAGS': '0x0', 09:18:19 INFO - 'XPC_SERVICE_NAME': '0', 09:18:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:19 INFO - Downloading/unpacking flake8 09:18:19 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:19 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 - 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:19 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:19 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:19 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:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:18:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:18:21 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:21 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:21 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:21 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:21 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:21 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:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:18:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:18:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:18:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:18:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:18:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:18:23 INFO - no previously-included directories found matching 'docs/_build' 09:18:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:18:23 INFO - Running setup.py install for pep8 09:18:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:18:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:18:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:18:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:18:23 INFO - no previously-included directories found matching 'docs/_build' 09:18:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:18:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:18:23 INFO - Cleaning up... 09:18:23 INFO - Return code: 0 09:18:23 INFO - Running post-action listener: _resource_record_post_action 09:18:23 INFO - Running post-action listener: _start_resource_monitoring 09:18:23 INFO - Starting resource monitoring. 09:18:23 INFO - ##### 09:18:23 INFO - ##### Running install step. 09:18:23 INFO - ##### 09:18:23 INFO - Running pre-action listener: _resource_record_pre_action 09:18:23 INFO - Running main action method: install 09:18:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:23 INFO - Reading from file tmpfile_stdout 09:18:23 INFO - Detecting whether we're running mozinstall >=1.0... 09:18:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:18:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:18:23 INFO - Reading from file tmpfile_stdout 09:18:23 INFO - Output received: 09:18:23 INFO - Usage: mozinstall [options] installer 09:18:23 INFO - Options: 09:18:23 INFO - -h, --help show this help message and exit 09:18:23 INFO - -d DEST, --destination=DEST 09:18:23 INFO - Directory to install application into. [default: 09:18:23 INFO - "/builds/slave/test"] 09:18:23 INFO - --app=APP Application being installed. [default: firefox] 09:18:23 INFO - mkdir: /builds/slave/test/build/application 09:18:23 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:23 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:57 INFO - Reading from file tmpfile_stdout 09:18:57 INFO - Output received: 09:18:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:18:57 INFO - Running post-action listener: _resource_record_post_action 09:18:57 INFO - ##### 09:18:57 INFO - ##### Running run-tests step. 09:18:57 INFO - ##### 09:18:57 INFO - Running pre-action listener: _resource_record_pre_action 09:18:57 INFO - Running main action method: run_tests 09:18:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:57 INFO - 2016-04-07 09:18:57.252 screenresolution[1944:11360] starting screenresolution argv=screenresolution get 09:18:57 INFO - 2016-04-07 09:18:57.255 screenresolution[1944:11360] Display 0: 1600x1200x32@60 09:18:57 INFO - 2016-04-07 09:18:57.260 screenresolution[1945:11363] starting screenresolution argv=screenresolution list 09:18:57 INFO - Available Modes on Display 0 09:18:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:57 INFO - Intel Iris: 09:18:57 INFO - Chipset Model: Intel Iris 09:18:57 INFO - Type: GPU 09:18:57 INFO - Bus: Built-In 09:18:57 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:57 INFO - Vendor: Intel (0x8086) 09:18:57 INFO - Device ID: 0x0a2e 09:18:57 INFO - Revision ID: 0x0009 09:18:57 INFO - Displays: 09:18:57 INFO - Display: 09:18:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:57 INFO - Display Serial Number: 1600x1200 60 09:18:57 INFO - Main Display: Yes 09:18:57 INFO - Mirror: Off 09:18:57 INFO - Online: Yes 09:18:57 INFO - Rotation: Supported 09:18:57 INFO - Return code: 0 09:18:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:57 INFO - Python 2.7.3 09:18:57 INFO - Return code: 0 09:18:57 INFO - grabbing minidump binary from tooltool 09:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:57 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 0x110366e00>, '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 0x1101cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110361b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:57 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:57 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:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:57 INFO - Return code: 0 09:18:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:18:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:18:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:18:58 INFO - Return code: 0 09:18:58 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-0200', '--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:58 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-0200 --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:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LvURJh4dmx/Render', 09:18:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:58 INFO - 'HOME': '/Users/cltbld', 09:18:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:58 INFO - 'LOGNAME': 'cltbld', 09:18:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:58 INFO - 'MOZ_NO_REMOTE': '1', 09:18:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:58 INFO - 'NO_EM_RESTART': '1', 09:18:58 INFO - 'PAGER': '/bin/cat', 09:18:58 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:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:58 INFO - 'PWD': '/builds/slave/test', 09:18:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:58 INFO - 'SHELL': '/bin/bash', 09:18:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.176pnwAAZL/Listeners', 09:18:58 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:18:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:58 INFO - 'USER': 'cltbld', 09:18:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:58 INFO - 'XPC_FLAGS': '0x0', 09:18:58 INFO - 'XPC_SERVICE_NAME': '0', 09:18:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:58 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-0200', '--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:58 INFO - mozversion application_buildid: 20160406112610 09:18:58 INFO - mozversion application_changeset: 55f0de142be46043246b18108299aebf2bd95a64 09:18:58 INFO - mozversion application_display_name: Nightly 09:18:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:58 INFO - mozversion application_name: Firefox 09:18:58 INFO - mozversion application_remotingname: firefox 09:18:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:18:58 INFO - mozversion application_vendor: Mozilla 09:18:58 INFO - mozversion application_version: 48.0a1 09:18:58 INFO - mozversion platform_buildid: 20160406112610 09:18:58 INFO - mozversion platform_changeset: 55f0de142be46043246b18108299aebf2bd95a64 09:18:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:18:58 INFO - mozversion platform_version: 48.0a1 09:18:58 INFO - using testdate: 1460045938 09:18:58 INFO - actual date: 1460045938 09:18:58 INFO - starting webserver on 'localhost:49649' 09:18:58 INFO - SUITE-START | Running 2 tests 09:18:58 INFO - TEST-START | dromaeo_css 09:18:58 INFO - operating with platform_type : mac_ 09:18:58 INFO - Initialising browser for dromaeo_css test... 09:18:58 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmpcH2Wtc/profile http://localhost:49649/getInfo.html) 09:19:01 INFO - PROCESS | 1963 | 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:19:01 INFO - PROCESS | 1963 | __metrics Screen width/height:1600/1200 09:19:01 INFO - PROCESS | 1963 | colorDepth:24 09:19:01 INFO - PROCESS | 1963 | Browser inner width/height: 1024/689 09:19:01 INFO - PROCESS | 1963 | __metrics 09:19:02 ERROR - PROCESS | 1963 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2881: TypeError: this.tabs is undefined 09:19:02 INFO - TEST-INFO | 1963: exit 0 09:19:02 INFO - Browser initialized. 09:19:02 INFO - Running cycle 1/1 for dromaeo_css test... 09:19:02 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmpcH2Wtc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:14 INFO - PROCESS | 1966 | RSS: Main: 246906880 09:19:14 INFO - PROCESS | 1966 | 09:20:04 INFO - PROCESS | 1966 | 1460046004188 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:15 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49649/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49649/tests/dromaeo/cssquery-ext.html) 09:22:16 INFO - PROCESS | 1966 | RSS: Main: 259555328 09:22:16 INFO - PROCESS | 1966 | 09:25:18 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49649/tests/dromaeo/cssquery-ext.html (next: http://localhost:49649/tests/dromaeo/cssquery-jquery.html) 09:25:19 INFO - PROCESS | 1966 | RSS: Main: 271384576 09:25:19 INFO - PROCESS | 1966 | 09:28:20 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49649/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49649/tests/dromaeo/cssquery-mootools.html) 09:28:21 INFO - PROCESS | 1966 | RSS: Main: 273420288 09:28:21 INFO - PROCESS | 1966 | 09:31:23 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49649/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49649/tests/dromaeo/cssquery-prototype.html) 09:31:23 INFO - PROCESS | 1966 | RSS: Main: 274984960 09:31:23 INFO - PROCESS | 1966 | 09:34:26 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49649/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49649/tests/dromaeo/cssquery-yui.html) 09:34:27 INFO - PROCESS | 1966 | RSS: Main: 277782528 09:34:27 INFO - PROCESS | 1966 | 09:37:29 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:49649/tests/dromaeo/cssquery-yui.html (next: http://localhost:49649/tests/dromaeo/cssquery-dojo.html) 09:37:29 INFO - PROCESS | 1966 | RSS: Main: 278773760 09:37:29 INFO - PROCESS | 1966 | 09:37:29 INFO - PROCESS | 1966 | __start_tp_report 09:37:29 INFO - PROCESS | 1966 | _x_x_mozilla_page_load 09:37:29 INFO - PROCESS | 1966 | _x_x_mozilla_page_load_details 09:37:29 INFO - PROCESS | 1966 | |i|pagename|runs| 09:37:29 INFO - PROCESS | 1966 | |0;dojo.html;3399.490076488528;3484.3205574912895;3490.581130264368;3531.699805516531;3589.2283159120784;5822.155787410828;5830.766769329232;5840.620359676618;5856.531477481802;5874.676892770901;5699.857503562413;5805.622634528752;5815.709214539267;5898.705064746756;5958.106284057393;5192.480751924815;5247.842564723045;5262.605304602151;5289.391719952197;5308.575313974892;347.1748161728853;5267.051930652481;5270.130428479296;5293.100172970604;5312.229726689628;5206.713630750307;5224.817131400383;5248.186531087668;5260.789568417259;5283.580608346144;5155.9742201288755;5290.735463226823;5298.09932311508;5317.468253174651;5322.707251101227;5171.689698618095;5175.171972484422;5227.817026404057;5274.129768588178;5306.973465132688;5578.30271216098;5628.634138780966;5671.943280567224;5681.261436013333;5712.028955077605;6158.291796443422;6197.411245931629;6229.626222426669;6266.216722909637;6281.811545653638;6214.40963108504;6277.686115694197;6283.151774510428;6297.086922396227;6307.400796924285;5967.313758917693;5988.281406231236;5989.348302571676;5993.970030149865;6037.9396206038255;5687.289088863892;6013.6692481913915;6279.591826531261;6316.873662526724;6326.177596912372;5168.586513078944;6242.781502647476;6243.188385509855;6255.155554000243;6264.874702505925;6003.489703375175;6241.906371404432;6281.371862813773;6305.3379395163765;6312.5581209314905;4526.072155208174;5182.222666600041;5965.612235204698;6103.580118178676;6150.907736383958;5027.497250274943;6077.574569780161;6259.1550140730415;6260.467860231931;6276.6547839868335;4893.657443978958;4940.333055037596;4958.454569997298;4958.553730164231;4994.550490455877;4398.318260669537;4402.3176407656865;4500.752458614807;4510.030343476168;4536.092781443725;5489.3687225969525;5789.739461724233;5867.735951882176;5869.501092407193;5907.084401917709;6291.779787707408;6298.0552917062805;6313.621182729051;6324.2410910691015;6338.809835704937;5755.59710820247;5836.212111364912;5844.298684157929;5888.263967004124;5916.556258280646;4968.882001549622;5433.0763770159165;5441.183822426668;5462.890742185214;5471.589630777628;6243.282022567372;6263.154474145932;6463.15978922737;6472.935270647234;6483.837904052342;6189.566730328834;6224.470919971853;6272.5922815016875;6285.214348206474;6289.779857704958;6419.518536109717;6424.678766061772;6442.677866106769;6452.387023232785;6456.677166141768;6059.697015149313;6147.108669242845;6170.845728856814;6213.254409470893;6227.283862355887;6196.783112591038;6245.750169993149;6255.687215639109;6269.749210031548;6271.811845644638;6131.356207598138;6145.569810113249;6159.414855588712;6167.814965551041;6180.907286390618;5951.851203719942;6076.787312444043;6140.565599444434;6154.353792851686;6162.876742465036;5906.291245050621;5963.552733544915;5981.371955944737;6125.989211780185;6126.548209435115;6111.816645500642;6172.845678858064;6176.289726681489;6184.257889053342;6198.938010620016;6035.18524999137;6066.636001839904;6103.816885493442;6124.050772130327;6132.509399248139 09:37:29 INFO - PROCESS | 1966 | |1;ext.html;446.0476881857236;570.2899889637403;606.940888150178;607.1560530862102;623.5759683415278;35313.293734125305;38858.417123743166;39307;39384.99999999996;39397.80301098498;40942.59057409425;40957.38563921536;41288;41616;41631.79184104083;9487.28845336599;9620.855687164698;9627.951860240724;9646;9763.560639771193;7768.262015108556;8673.959124904988;9599.808003839918;9609.231261499099;9635.614575416976;9569.760755981086;9582.52087395631;9614.80770384592;9629.133377995979;9638.903610963871;9532.95233523828;9536.094071063204;9543.761405964871;9584.808303833885;9618.038196180396;8252.133525979805;9454.243660507143;9463.763405914908;9584.424934503882;9628.18160456362;30836.074917752503;38279.23441531154;39584.6145384913;39700.60299396998;39880.002999924945;2598.5712357461;2823.1671656861276;2916.839573823439;3138.3880143372053;3448;27642;28630.997915072872;28693.28266793326;29086.27284317909;29397.412051758845;2640.7095219525836;2711.8644067796727;2717.0897749253827;2722.233221144336;2811.437712457496;372125;372534;375160.12419937726;377056;377800;46698.76650616725;47182;47185;47577.76211118922;62934.68532657399;45259.547404526194;46026.53973460222;46041;46435.76782116135;46460.53539464562;15957.760633590506;15987.44043958456;16166.83833161677;16175.838241617668;16181.757273640906;12620.747585048248;12820.423080961193;12863.549775757803;12939;13049.151805132637;14842.332095055748;14943.925280373529;15010.549683509513;15061.849381506045;15299.694006119815;5361.785528578891;6233.3143354231;6280.340564240689;6345.936540634534;6347.428731414195;918.4535201555065;966.7969726357544;968.874046373981;974.7563109222694;1039.0648416425279;25915.74084259171;28318.442485663076;29582.112536623936;29762;29974;1070.2829104500004;1097.3415950429678;1119.9552017919193;1132.1282612388416;1137.4199158429096;15161.772573411408;15289.388424463119;15339.079655220723;15404.61488462775;15540;507473;508062;512861;516591;526449;59000;59288.70355648194;60091;60206;60523;1405.325443786988;1417.7660685986903;1434.2470203143225;1448.6523234423873;1458.7593047147313;25385.207627160627;27724.8613756938;29635.703642964156;30195.396092078474;30321.848390757907;45393.86515337143;52123.739381302854;53154.73422632862;53400.73299633477;53645;414763;476276;476988;477423;478314;34411.311773764886;44754.3286850704;45688.08623827439;45873.85315367009;46719;34543.13642158966;42872.571274286856;43266.918327042076;44327.778361109275;44414.3337849939;30682.693173067983;34565.48151777675;38205;38551.6144838548;39449.80275098606;30943.690563094082;36334.90995270146;38514.80742596269;39802.20395592014;39844.80077599594;33715;35551.82224088863;36959.815200923826;37262.06844828901;39879;32926.83536582398;33433.49849752207;33991.98024059282;34185.658143418244;34375.656243437246;20755.584888302452;24807.354467778652;25817.741822581535;25968.961241550125;26015.73984260133 09:37:29 INFO - PROCESS | 1966 | |2;jquery.html;303.8890804856228;312.85495087978006;333.33000668656;353.7735849056606;356.61129368987804;19289.421317360484;19775.802241977577;20483;20495.385138445872;20677.483062923417;16889.057758357794;19977.901215433147;20836.791632083674;21029;21060.262890798826;5430;5645.379008309083;5697.8005769798065;5722.113072473752;5754.539636829044;5518.117101263799;5794.362620111784;5813.883722325551;5864.384239654839;5941.0494320908665;5795.15970184323;5817.389174136718;5821.738021789009;5827.184194212792;5848.327442344143;5525.254090697745;5659.151127330933;5672.829815105553;5764.07774756037;5813.215215945836;4918.188498897677;4928.778204980785;4971.254311853215;5005.74971251436;5022.874428139326;24391.87804060986;24433.022679092814;24785.504289914283;25044.373890652696;25141.748582514123;2094.539201375695;2113.9894300528554;2162.329677799871;2225.477012901983;2256.4697296135396;13991.950603704763;14339.28303584827;14685.265736713229;14734;14825.258737063104;1638.4265507072548;1650.3811070848433;1700.846923776866;1716.3306310538899;1738.0961899812028;66613;81155.18844811535;85834;88453;89500;10255.897441025643;10311.535980880879;10380.584776608852;10405.115565177044;10498.685039448828;12567;12626;12638.178518396275;12642.810357844639;12762.830318845818;8382.748517544484;8405.033421156522;8546.487210767375;8594.097619749962;8638.91361086381;8793.91206087931;8832.38173327861;8859.158379953895;8874.156955089256;8889.377743558018;5900.232969713909;5988.101784732325;6005.549583781147;6009.248843894513;6114.449699527064;1586.50818246344;1781.0916432619458;1814.0838876367613;1816.7910690270523;1818.7180986947044;872.2193636695249;946.654471118037;971.3006635222629;1003.9408424112631;1057.841323801436;16354.591135221477;19131.23475060982;20206;20686.68969965452;20863.79136208649;887.8179973105498;911.3984770051734;953.9856902146473;955.4124213608719;993.9304248702522;10034.24743144267;10303.793924121475;10509.369437833755;10547.419891905865;10577.62978295756;606015;609272.9536352379;618047;618665.9066704637;628435;112801;119554.81558145423;120642;122440;122466;1009.0766948242423;1012.2509343086064;1024.8718910136233;1043.8121138195047;1048.8898665639997;15392.153431561372;16715.58211044685;18307.63384732271;18613.813861861207;18629.06854657289;47223;49337.75331123322;49949.75025124851;52661;53016.2047569279;50272.74863625658;51991;66726.66636666785;67729;68779.65610172118;6669.264043316189;6727.966360168168;7544.8491030180185;7719.922800772145;7976.7208147714555;8819.514926678838;9338.533073346442;9529.285303602397;11394.316341019565;12449.128561001005;10223.591056357662;10574.947125264633;10586.629467968583;11044.668659940215;11137.387443690675;8424.5787710613;10413.47932603382;10450.42522661261;10833.729156771144;10885.836712449161;10868.347899126078;11174.217804753915;11259.831102533632;11262.605808796981;11274.379909105079;7549.50928189666;7669.233076692286;7801.687932482864;7881.330086942674;8012.078610959814;8121.553314567519;10263.973602639677;10823.729406764895;10865;11158.44207789591 09:37:29 INFO - PROCESS | 1966 | |3;mootools.html;1585.0093691442848;1596.6567188054566;1869.1588785046733;1944.9513762155943;1968.507873031742;7904.6442910069045;8589.570521473917;8627.913720862789;8857.11428857114;8869.866951995717;8454.492730436163;8508.191721786436;8589.87115193271;8696.434731742433;8699.65201391945;2470.1231062972656;2488.9377765555914;2511.635812807142;2513.45960618467;2532.4175439648893;2375.679283296759;2431.209856796548;2448.9142879999204;2459.4220358215807;2466.5930121529927;2479.950400991979;2494.725580186178;2496.400863792686;2526.835755675875;2530.101813856082;2435.8538487690707;2447.3269850791175;2450.4486490539484;2483.242611003643;2512.560301947161;2469.4567195217205;2478.8140889433366;2498.337940445765;2498.400383907849;2501.9866953883525;8775.210231079169;8807.163319484704;8819.206271435538;8832.646694132227;8842.159994800548;5418.0789265824515;5425.294711687495;5493.176023596453;5547.639403438763;5576.856744367395;3546.627604101558;3623.547056617923;3666.926661466782;3671.155634204148;3673.3571624965793;804.6097642643314;1004.1514919892679;1020.6070662794878;1028.0336483705294;1049.7375656085978;11562.826557601558;11739.941300293529;11744.060475161967;11951.103667224992;11970.401479926057;4336.956630433655;4746.1694203514935;4848.684835485682;4907.263910413467;5047.965167140727;9223.308251881135;9245.953770231241;9271.304652151115;9337.90662093384;9382.718518444457;4412.889677758082;5352.411234764173;5360.544353729977;5395.678058875601;5396.892062158735;4289.871303860889;4342.478902531717;4831.444383895827;4907.877303067452;4929.926051109236;3092.38152369527;3692.132348898007;3733.738638295293;3772.6227377262603;3781.8487260509273;1179.2511755035614;1186.2289511817214;1192.1714408486193;1196.7367179220557;1199.8140288255333;1339.7923321885123;1355.105630284008;1380.813590165335;1386.4592808804373;1400.4888215801363;7216.737071012657;8078.919210807934;8173.877391839127;8361;8372.58137093143;510.64255021484365;515.443321213089;520.4639221601756;520.6225486522317;524.4231345519974;6159.076138579159;6169;6219.284782250009;6224;6234.127222188898;10464.790704186025;10750.94624526888;10880.727981800519;10926.508307126198;10981.890181098086;10454.425006624557;10592.523336449878;10737.731556711145;10819.242653014371;10833.133349332034;8296.045954715284;8318.251357377743;8373.07896131425;8402.831943361221;8457.53483558398;7815.609219539114;7912.604369781373;7988.081370642218;8057.959710201411;8148.22591853773;9370.812583748151;9371;9661.71014869555;9789.46157961319;9822.116009559173;9726.319157658896;9812.901870981199;10058.19534437258;10163.644272450429;10263;6321.8735625288155;6690.899636505554;6981.930180698128;7011.859762804817;7134.964325178341;8246.670133194844;8285.875711864206;8333.874926885035;8334.6666133354;8335.49987000782;7224.74713385029;7325.816854578465;7406.888896666662;7440.516366436164;7518.473706840688;7262.346388825031;7275.8181045472165;7305.977163197051;7323.304286092813;7351.228121047213;7712.267334603203;7778.649960751893;7810.218978102144;7822.335101516434;7824.608769561613;6780.254172040973;6920.7231710731;6959.8608027838145;6986.476014298846;7083.1854336749875;7065.894011589728;7075.150981882207;7130.144382674112;7130.714771409085;7157.997880296659 09:37:29 INFO - PROCESS | 1966 | |4;prototype.html;271.55329483000463;329.10647591788296;332.3834287396877;337.7669408108405;340.54537192847545;6755.5271131020845;6842.521023528355;6893.241743408234;6913.965430172855;6940.930590694085;6682.565633233837;6754.560953538016;6829.6926638301275;6847.623380714059;6849.554778939366;4726.503717109706;4867.853964381056;4977.5022497750115;4978.352814134158;5010.448850626437;3729;4957.405111386639;5011.799528018875;5031.52200540948;5052.0906236877345;4969.329140566031;4976.4774698656665;4980.6762560433635;4989.825356112537;5021.171506701408;4996.1756310208775;5000.399952005764;5003.249512573125;5004.524570165829;5023.874403139914;4293.785310734451;4295.646871235551;4300;4308.073764140693;4314.697971142022;4915.778789954461;5510.421405752379;6364.835955775059;6474.158359413246;6491.740330386779;5129.9483605860705;5806.680632565207;5893.0276504376725;5938.346781854036;5959.910601340984;5806.65160090396;7140.7857764267155;7253.746118885866;7277.599732014737;7300.233475485051;4768.308595253703;4829.637777166727;4829.806807727687;4830.686005409672;4845.975770121162;6097.756089756404;7609.201033891472;7674.117476490163;7682.385409167309;7700.768976930701;4705.623550116051;4868.050730107666;4906.730129842893;4912.631552633567;4921.827736029221;4975.203967365204;5000.924801167766;5009.699418034929;5010.824621138243;5015.6489045767175;5797.884042319214;5921.7631294747725;5922.733476993546;5934.13954976526;5949.167116603679;7278.797329175146;7282.927170728225;7295.744648937261;7297.525660832029;7318.634068296564;1717.9742303865453;1747.2137538107902;1751.3695069774872;1755.8419742223261;1756.8067512573607;1071.2929466552193;1118.6532175025768;1121.8429419881222;1126.6282126898104;1142.0749193153572;6559.278479367265;6626.105475760807;6670.866582668319;6677.699503522354;6679.966600167065;6035.305939816977;7544.8491030180185;7550.509216900884;7553.395728341719;7573.939648449222;6449.451796597342;6563;6743.224529179109;6743.730250789913;6775.288594697585;3333.8833140839956;3979.7015223857748;4025.6175663311938;4048.5344185418467;4064.3700226464944;6010.9699451502465;6472.611643301323;7869.960650196713;7977.7207797726805;8012.679492820338;7429.5542267464125;7880.3301719354495;7894.763157105296;7905.486143400775;7926.365890728728;6483.384078512534;7900.170482099296;7949.205079492121;7958.761237162895;7963.482373645695;7560.6219689016425;7604.8479030417975;7612.429067820047;7634.083909930844;7635.083789945242;7421.851562968818;7426.962865185639;7431.331180193809;7466.216047315171;7467.92532074694;6776.864462710817;7483.251674832473;7900.486468379537;7907.644156013108;7942.523448593103;4261.083866968616;4277.593628605277;4611.0547337795;4640.907181856412;4689.015306785477;7291.869760187179;7516.098068231847;7542.924570754222;7555.282248186413;7563.281488258606;5447.4824891635235;5514.6691198528215;7289.635518223961;7299.05112335393;7301.379382752525;6993.755218567326;7283.344498995116;7304.780856574312;7318.524295920748;7329.670164842488;5743.368229494668;7189.137303523611;7199.496035277481;7206.89189662145;7220.241874603301;5738.655680659007;5963.642181469126;7193.884947833094;7362.7423040193335;7378.188399276182;7126.930960355988;7147.249538798351;7158.928410715826;7161.677724502514;7204.819879502845 09:37:29 INFO - PROCESS | 1966 | |5;yui.html;659.6141257364442;683.624006796262;739.9001134846795;755.773268019594;758.8596109719706;14374.49689260876;14433.566992990227;14570.635734106652;14573.927130364347;14593.708125837491;15564.53306400807;15812.209389530506;15853.207339633002;15861.444849430272;15877.920610396961;804.1355542791493;827.5435233988194;894.6466145872377;899.2401420799447;900.459724165502;721.7437809577604;885.3359980014989;900.5722281916103;900.8603666431713;902.823949329881;939.178219058324;940.5109343141562;942.3074040580168;943.7310366545536;944.2115833279186;760.9923900760976;923.1875949164726;928.2620316848122;936.7751739582485;939.5067589515525;676.7529851604178;677.16708448608;690.7547820523693;698.3016983016983;698.559907258429;14365.497207597684;14631.70736585273;14691.11853288806;14716.632084197874;14790.704185916222;12157.148999570034;12399;12422.875771242352;12580.4338804754;12629.2422454653;9975.551100200508;10055.446950417723;10063.748406289827;10125.139363154141;10461.372317660966;3425.0409885232175;3492.9650703492894;3494.9825250873837;3561.465780132975;3627.2201476682376;79433;80027.7995830063;80692.3861522772;80962;81119;8792.8681069784;8879.023307436177;8950.552472376356;8985.46087234768;9101.863472047924;20063.598728025358;20703;20750.06624701892;20756.68864967027;20770.58458830845;7321.414286857039;7328.9267107329315;7333.193348731742;7340.632968351668;7421.962890185622;4086.141910198832;4128.896777580585;4134.834606615762;4140.0891803803115;4141.7722025288895;3485.3203625292826;3497.0208341664375;3536.911577210539;3559.3059353425833;3573.12458447682;634.8222497700651;645.686841881687;645.7707513832615;650.4503694378243;658.3613894522306;457.3574128349674;467.2430662327052;584.95612829038;585.3842452263845;592.4371846745523;7787.221277872168;7963.163867793798;8186.836263274701;8242.464239824392;8423.115572864883;467.78949472737406;512.8743457852776;532.9680219186861;554.9649902931004;586.369652623423;3772.5472943246987;3826.253880493333;3857.9421308680394;3864.662177965515;3910.765354078764;74879.90082132368;83323.33353332899;84773;86149.13850861411;94296.52851735834;77949;79149.60425197953;79836;79872;80574;70198.64900675464;70290;70722;71233.64383178052;71812.64093679603;7607.961960190164;8463.57682115904;8465.746027619181;8514.31885449175;8595.140485951355;49787.75106124446;50223.49776502188;51548.226776599135;52006.479935200165;52501.21248181357;64195.67902160459;69834;70954.64522677353;71050;71382;10634.73413164677;10967.396793176453;11111.444427778417;11175.27360721583;11347.432628368713;11447.198696091195;11745.765084698429;12150.270983741004;12255.019598431925;12557.560485382968;11287.66137015858;11384.260023098472;11442;11708.297502149899;11881.465334060162;8438.957805210935;11229.3262404256;11295.830562541732;11297.43512824372;11475.885241147482;10967.561297548327;11116.16628752863;11153.944230278797;11243.887561124284;11575.073994080285;7317.085364329459;9206.355555111079;9261.212796912338;9486;9581.233501320017;9126.771830704285;9477;9500.809983800147;9515.714528564155;9549.379290346105 09:37:29 INFO - PROCESS | 1966 | __end_tp_report 09:37:29 INFO - PROCESS | 1966 | __start_cc_report 09:37:29 INFO - PROCESS | 1966 | _x_x_mozilla_cycle_collect,380 09:37:29 INFO - PROCESS | 1966 | __end_cc_report 09:37:29 INFO - PROCESS | 1966 | __startTimestamp1460047049794__endTimestamp 09:37:29 INFO - PROCESS | 1966 | 09:37:29 INFO - PROCESS | 1966 | ------- Summary: start ------- 09:37:29 INFO - PROCESS | 1966 | Number of tests: 6 09:37:29 INFO - PROCESS | 1966 | 09:37:29 INFO - PROCESS | 1966 | [#0] dojo.html Cycles:165 Average:5737.66 Median:5989.35 stddev:764.61 (12.8%) stddev-sans-first:744.63 09:37:29 INFO - PROCESS | 1966 | Values: 3399.5 3484.3 3490.6 3531.7 3589.2 5822.2 5830.8 5840.6 5856.5 5874.7 5699.9 5805.6 5815.7 5898.7 5958.1 5192.5 5247.8 5262.6 5289.4 5308.6 347.2 5267.1 5270.1 5293.1 5312.2 5206.7 5224.8 5248.2 5260.8 5283.6 5156.0 5290.7 5298.1 5317.5 5322.7 5171.7 5175.2 5227.8 5274.1 5307.0 5578.3 5628.6 5671.9 5681.3 5712.0 6158.3 6197.4 6229.6 6266.2 6281.8 6214.4 6277.7 6283.2 6297.1 6307.4 5967.3 5988.3 5989.3 5994.0 6037.9 5687.3 6013.7 6279.6 6316.9 6326.2 5168.6 6242.8 6243.2 6255.2 6264.9 6003.5 6241.9 6281.4 6305.3 6312.6 4526.1 5182.2 5965.6 6103.6 6150.9 5027.5 6077.6 6259.2 6260.5 6276.7 4893.7 4940.3 4958.5 4958.6 4994.6 4398.3 4402.3 4500.8 4510.0 4536.1 5489.4 5789.7 5867.7 5869.5 5907.1 6291.8 6298.1 6313.6 6324.2 6338.8 5755.6 5836.2 5844.3 5888.3 5916.6 4968.9 5433.1 5441.2 5462.9 5471.6 6243.3 6263.2 6463.2 6472.9 6483.8 6189.6 6224.5 6272.6 6285.2 6289.8 6419.5 6424.7 6442.7 6452.4 6456.7 6059.7 6147.1 6170.8 6213.3 6227.3 6196.8 6245.8 6255.7 6269.7 6271.8 6131.4 6145.6 6159.4 6167.8 6180.9 5951.9 6076.8 6140.6 6154.4 6162.9 5906.3 5963.6 5981.4 6126.0 6126.5 6111.8 6172.8 6176.3 6184.3 6198.9 6035.2 6066.6 6103.8 6124.1 6132.5 09:37:29 INFO - PROCESS | 1966 | 09:37:29 INFO - PROCESS | 1966 | [#1] ext.html Cycles:180 Average:59483.46 Median:39423.80 stddev:120899.64 (306.7%) stddev-sans-first:121157.54 09:37:29 INFO - PROCESS | 1966 | Values: 446.0 570.3 606.9 607.2 623.6 35313.3 38858.4 39307.0 39385.0 39397.8 40942.6 40957.4 41288.0 41616.0 41631.8 9487.3 9620.9 9628.0 9646.0 9763.6 7768.3 8674.0 9599.8 9609.2 9635.6 9569.8 9582.5 9614.8 9629.1 9638.9 9533.0 9536.1 9543.8 9584.8 9618.0 8252.1 9454.2 9463.8 9584.4 9628.2 30836.1 38279.2 39584.6 39700.6 39880.0 2598.6 2823.2 2916.8 3138.4 3448.0 27642.0 28631.0 28693.3 29086.3 29397.4 2640.7 2711.9 2717.1 2722.2 2811.4 372125.0 372534.0 375160.1 377056.0 377800.0 46698.8 47182.0 47185.0 47577.8 62934.7 45259.5 46026.5 46041.0 46435.8 46460.5 15957.8 15987.4 16166.8 16175.8 16181.8 12620.7 12820.4 12863.5 12939.0 13049.2 14842.3 14943.9 15010.5 15061.8 15299.7 5361.8 6233.3 6280.3 6345.9 6347.4 918.5 966.8 968.9 974.8 1039.1 25915.7 28318.4 29582.1 29762.0 29974.0 1070.3 1097.3 1120.0 1132.1 1137.4 15161.8 15289.4 15339.1 15404.6 15540.0 507473.0 508062.0 512861.0 516591.0 526449.0 59000.0 59288.7 60091.0 60206.0 60523.0 1405.3 1417.8 1434.2 1448.7 1458.8 25385.2 27724.9 29635.7 30195.4 30321.8 45393.9 52123.7 53154.7 53400.7 53645.0 414763.0 476276.0 476988.0 477423.0 478314.0 34411.3 44754.3 45688.1 45873.9 46719.0 34543.1 42872.6 43266.9 44327.8 44414.3 30682.7 34565.5 38205.0 38551.6 39449.8 30943.7 36334.9 38514.8 39802.2 39844.8 33715.0 35551.8 36959.8 37262.1 39879.0 32926.8 33433.5 33992.0 34185.7 34375.7 20755.6 24807.4 25817.7 25969.0 26015.7 09:37:29 INFO - PROCESS | 1966 | 09:37:29 INFO - PROCESS | 1966 | [#2] jquery.html Cycles:180 Average:33533.53 Median:25093.06 stddev:101713.88 (405.3%) stddev-sans-first:101968.61 09:37:29 INFO - PROCESS | 1966 | Values: 303.9 312.9 333.3 353.8 356.6 19289.4 19775.8 20483.0 20495.4 20677.5 16889.1 19977.9 20836.8 21029.0 21060.3 5430.0 5645.4 5697.8 5722.1 5754.5 5518.1 5794.4 5813.9 5864.4 5941.0 5795.2 5817.4 5821.7 5827.2 5848.3 5525.3 5659.2 5672.8 5764.1 5813.2 4918.2 4928.8 4971.3 5005.7 5022.9 24391.9 24433.0 24785.5 25044.4 25141.7 2094.5 2114.0 2162.3 2225.5 2256.5 13992.0 14339.3 14685.3 14734.0 14825.3 1638.4 1650.4 1700.8 1716.3 1738.1 66613.0 81155.2 85834.0 88453.0 89500.0 10255.9 10311.5 10380.6 10405.1 10498.7 12567.0 12626.0 12638.2 12642.8 12762.8 8382.7 8405.0 8546.5 8594.1 8638.9 8793.9 8832.4 8859.2 8874.2 8889.4 5900.2 5988.1 6005.5 6009.2 6114.4 1586.5 1781.1 1814.1 1816.8 1818.7 872.2 946.7 971.3 1003.9 1057.8 16354.6 19131.2 20206.0 20686.7 20863.8 887.8 911.4 954.0 955.4 993.9 10034.2 10303.8 10509.4 10547.4 10577.6 606015.0 609273.0 618047.0 618665.9 628435.0 112801.0 119554.8 120642.0 122440.0 122466.0 1009.1 1012.3 1024.9 1043.8 1048.9 15392.2 16715.6 18307.6 18613.8 18629.1 47223.0 49337.8 49949.8 52661.0 53016.2 50272.7 51991.0 66726.7 67729.0 68779.7 6669.3 6728.0 7544.8 7719.9 7976.7 8819.5 9338.5 9529.3 11394.3 12449.1 10223.6 10574.9 10586.6 11044.7 11137.4 8424.6 10413.5 10450.4 10833.7 10885.8 10868.3 11174.2 11259.8 11262.6 11274.4 7549.5 7669.2 7801.7 7881.3 8012.1 8121.6 10264.0 10823.7 10865.0 11158.4 09:37:29 INFO - PROCESS | 1966 | 09:37:29 INFO - PROCESS | 1966 | [#3] mootools.html Cycles:180 Average:6001.22 Median:5396.29 stddev:3190.09 (59.1%) stddev-sans-first:3181.77 09:37:29 INFO - PROCESS | 1966 | Values: 1585.0 1596.7 1869.2 1945.0 1968.5 7904.6 8589.6 8627.9 8857.1 8869.9 8454.5 8508.2 8589.9 8696.4 8699.7 2470.1 2488.9 2511.6 2513.5 2532.4 2375.7 2431.2 2448.9 2459.4 2466.6 2480.0 2494.7 2496.4 2526.8 2530.1 2435.9 2447.3 2450.4 2483.2 2512.6 2469.5 2478.8 2498.3 2498.4 2502.0 8775.2 8807.2 8819.2 8832.6 8842.2 5418.1 5425.3 5493.2 5547.6 5576.9 3546.6 3623.5 3666.9 3671.2 3673.4 804.6 1004.2 1020.6 1028.0 1049.7 11562.8 11739.9 11744.1 11951.1 11970.4 4337.0 4746.2 4848.7 4907.3 5048.0 9223.3 9246.0 9271.3 9337.9 9382.7 4412.9 5352.4 5360.5 5395.7 5396.9 4289.9 4342.5 4831.4 4907.9 4929.9 3092.4 3692.1 3733.7 3772.6 3781.8 1179.3 1186.2 1192.2 1196.7 1199.8 1339.8 1355.1 1380.8 1386.5 1400.5 7216.7 8078.9 8173.9 8361.0 8372.6 510.6 515.4 520.5 520.6 524.4 6159.1 6169.0 6219.3 6224.0 6234.1 10464.8 10750.9 10880.7 10926.5 10981.9 10454.4 10592.5 10737.7 10819.2 10833.1 8296.0 8318.3 8373.1 8402.8 8457.5 7815.6 7912.6 7988.1 8058.0 8148.2 9370.8 9371.0 9661.7 9789.5 9822.1 9726.3 9812.9 10058.2 10163.6 10263.0 6321.9 6690.9 6981.9 7011.9 7135.0 8246.7 8285.9 8333.9 8334.7 8335.5 7224.7 7325.8 7406.9 7440.5 7518.5 7262.3 7275.8 7306.0 7323.3 7351.2 7712.3 7778.6 7810.2 7822.3 7824.6 6780.3 6920.7 6959.9 6986.5 7083.2 7065.9 7075.2 7130.1 7130.7 7158.0 09:37:29 INFO - PROCESS | 1966 | 09:37:29 INFO - PROCESS | 1966 | [#4] prototype.html Cycles:180 Average:5864.69 Median:6487.56 stddev:1879.76 (29.0%) stddev-sans-first:1837.56 09:37:29 INFO - PROCESS | 1966 | Values: 271.6 329.1 332.4 337.8 340.5 6755.5 6842.5 6893.2 6914.0 6940.9 6682.6 6754.6 6829.7 6847.6 6849.6 4726.5 4867.9 4977.5 4978.4 5010.4 3729.0 4957.4 5011.8 5031.5 5052.1 4969.3 4976.5 4980.7 4989.8 5021.2 4996.2 5000.4 5003.2 5004.5 5023.9 4293.8 4295.6 4300.0 4308.1 4314.7 4915.8 5510.4 6364.8 6474.2 6491.7 5129.9 5806.7 5893.0 5938.3 5959.9 5806.7 7140.8 7253.7 7277.6 7300.2 4768.3 4829.6 4829.8 4830.7 4846.0 6097.8 7609.2 7674.1 7682.4 7700.8 4705.6 4868.1 4906.7 4912.6 4921.8 4975.2 5000.9 5009.7 5010.8 5015.6 5797.9 5921.8 5922.7 5934.1 5949.2 7278.8 7282.9 7295.7 7297.5 7318.6 1718.0 1747.2 1751.4 1755.8 1756.8 1071.3 1118.7 1121.8 1126.6 1142.1 6559.3 6626.1 6670.9 6677.7 6680.0 6035.3 7544.8 7550.5 7553.4 7573.9 6449.5 6563.0 6743.2 6743.7 6775.3 3333.9 3979.7 4025.6 4048.5 4064.4 6011.0 6472.6 7870.0 7977.7 8012.7 7429.6 7880.3 7894.8 7905.5 7926.4 6483.4 7900.2 7949.2 7958.8 7963.5 7560.6 7604.8 7612.4 7634.1 7635.1 7421.9 7427.0 7431.3 7466.2 7467.9 6776.9 7483.3 7900.5 7907.6 7942.5 4261.1 4277.6 4611.1 4640.9 4689.0 7291.9 7516.1 7542.9 7555.3 7563.3 5447.5 5514.7 7289.6 7299.1 7301.4 6993.8 7283.3 7304.8 7318.5 7329.7 5743.4 7189.1 7199.5 7206.9 7220.2 5738.7 5963.6 7193.9 7362.7 7378.2 7126.9 7147.2 7158.9 7161.7 7204.8 09:37:29 INFO - PROCESS | 1966 | 09:37:29 INFO - PROCESS | 1966 | [#5] yui.html Cycles:180 Average:18158.83 Median:645.73 stddev:25406.68 (3934.6%) stddev-sans-first:25443.97 09:37:29 INFO - PROCESS | 1966 | Values: 659.6 683.6 739.9 755.8 758.9 14374.5 14433.6 14570.6 14573.9 14593.7 15564.5 15812.2 15853.2 15861.4 15877.9 804.1 827.5 894.6 899.2 900.5 721.7 885.3 900.6 900.9 902.8 939.2 940.5 942.3 943.7 944.2 761.0 923.2 928.3 936.8 939.5 676.8 677.2 690.8 698.3 698.6 14365.5 14631.7 14691.1 14716.6 14790.7 12157.1 12399.0 12422.9 12580.4 12629.2 9975.6 10055.4 10063.7 10125.1 10461.4 3425.0 3493.0 3495.0 3561.5 3627.2 79433.0 80027.8 80692.4 80962.0 81119.0 8792.9 8879.0 8950.6 8985.5 9101.9 20063.6 20703.0 20750.1 20756.7 20770.6 7321.4 7328.9 7333.2 7340.6 7422.0 4086.1 4128.9 4134.8 4140.1 4141.8 3485.3 3497.0 3536.9 3559.3 3573.1 634.8 645.7 645.8 650.5 658.4 457.4 467.2 585.0 585.4 592.4 7787.2 7963.2 8186.8 8242.5 8423.1 467.8 512.9 533.0 555.0 586.4 3772.5 3826.3 3857.9 3864.7 3910.8 74879.9 83323.3 84773.0 86149.1 94296.5 77949.0 79149.6 79836.0 79872.0 80574.0 70198.6 70290.0 70722.0 71233.6 71812.6 7608.0 8463.6 8465.7 8514.3 8595.1 49787.8 50223.5 51548.2 52006.5 52501.2 64195.7 69834.0 70954.6 71050.0 71382.0 10634.7 10967.4 11111.4 11175.3 11347.4 11447.2 11745.8 12150.3 12255.0 12557.6 11287.7 11384.3 11442.0 11708.3 11881.5 8439.0 11229.3 11295.8 11297.4 11475.9 10967.6 11116.2 11153.9 11243.9 11575.1 7317.1 9206.4 9261.2 9486.0 9581.2 9126.8 9477.0 9500.8 9515.7 9549.4 09:37:29 INFO - PROCESS | 1966 | -------- Summary: end -------- 09:37:29 INFO - PROCESS | 1966 | 09:37:30 INFO - TEST-INFO | 1966: exit 0 09:37:30 INFO - TEST-OK | dromaeo_css | took 1111343ms 09:37:30 INFO - TEST-START | kraken 09:37:30 INFO - operating with platform_type : mac_ 09:37:30 INFO - Initialising browser for kraken test... 09:37:30 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmp6YZD2o/profile http://localhost:49649/getInfo.html) 09:37:32 INFO - PROCESS | 1972 | 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:33 INFO - PROCESS | 1972 | __metrics Screen width/height:1600/1200 09:37:33 INFO - PROCESS | 1972 | colorDepth:24 09:37:33 INFO - PROCESS | 1972 | Browser inner width/height: 1024/689 09:37:33 INFO - PROCESS | 1972 | __metrics 09:37:33 ERROR - PROCESS | 1972 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2881: TypeError: this.tabs is undefined 09:37:34 INFO - TEST-INFO | 1972: exit 0 09:37:34 INFO - Browser initialized. 09:37:34 INFO - Running cycle 1/1 for kraken test... 09:37:34 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmp6YZD2o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:45 INFO - PROCESS | 1975 | RSS: Main: 247136256 09:37:45 INFO - PROCESS | 1975 | 09:37:48 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49649/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:49 INFO - PROCESS | 1975 | RSS: Main: 259350528 09:37:49 INFO - PROCESS | 1975 | 09:37:51 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49649/tests/kraken/driver.html?testName=audio-dft) 09:37:52 INFO - PROCESS | 1975 | RSS: Main: 268349440 09:37:52 INFO - PROCESS | 1975 | 09:37:55 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49649/tests/kraken/driver.html?testName=audio-fft) 09:37:56 INFO - PROCESS | 1975 | RSS: Main: 271208448 09:37:56 INFO - PROCESS | 1975 | 09:37:58 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49649/tests/kraken/driver.html?testName=audio-oscillator) 09:37:58 INFO - PROCESS | 1975 | RSS: Main: 273293312 09:37:58 INFO - PROCESS | 1975 | 09:38:00 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49649/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:38:01 INFO - PROCESS | 1975 | RSS: Main: 273793024 09:38:01 INFO - PROCESS | 1975 | 09:38:04 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49649/tests/kraken/driver.html?testName=imaging-darkroom) 09:38:04 INFO - PROCESS | 1975 | RSS: Main: 274001920 09:38:04 INFO - PROCESS | 1975 | 09:38:07 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49649/tests/kraken/driver.html?testName=imaging-desaturate) 09:38:08 INFO - PROCESS | 1975 | RSS: Main: 274022400 09:38:08 INFO - PROCESS | 1975 | 09:38:10 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49649/tests/kraken/driver.html?testName=json-parse-financial) 09:38:11 INFO - PROCESS | 1975 | RSS: Main: 274046976 09:38:11 INFO - PROCESS | 1975 | 09:38:13 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49649/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:38:13 INFO - PROCESS | 1975 | RSS: Main: 273924096 09:38:13 INFO - PROCESS | 1975 | 09:38:15 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49649/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:38:16 INFO - PROCESS | 1975 | RSS: Main: 274042880 09:38:16 INFO - PROCESS | 1975 | 09:38:18 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49649/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:38:18 INFO - PROCESS | 1975 | RSS: Main: 274354176 09:38:18 INFO - PROCESS | 1975 | 09:38:21 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49649/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:38:22 INFO - PROCESS | 1975 | RSS: Main: 274391040 09:38:22 INFO - PROCESS | 1975 | 09:38:24 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49649/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:25 INFO - PROCESS | 1975 | RSS: Main: 274599936 09:38:25 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49649/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49649/tests/kraken/driver.html?testName=ai-astar) 09:38:27 INFO - PROCESS | 1975 | RSS: Main: 276393984 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | __start_tp_report 09:38:27 INFO - PROCESS | 1975 | _x_x_mozilla_page_load 09:38:27 INFO - PROCESS | 1975 | _x_x_mozilla_page_load_details 09:38:27 INFO - PROCESS | 1975 | |i|pagename|runs| 09:38:27 INFO - PROCESS | 1975 | |0;ai-astar;114;105;114;116;114;130;113;113;115;99 09:38:27 INFO - PROCESS | 1975 | |1;audio-beat-detection;152;127;165;158;121;138;138;125;161;127 09:38:27 INFO - PROCESS | 1975 | |2;audio-dft;193;184;198;201;199;205;235;195;206;190 09:38:27 INFO - PROCESS | 1975 | |3;audio-fft;67;98;83;73;59;59;68;130;64;67 09:38:27 INFO - PROCESS | 1975 | |4;audio-oscillator;81;89;134;81;79;82;123;92;66;116 09:38:27 INFO - PROCESS | 1975 | |5;imaging-gaussian-blur;88;86;87;85;89;86;90;93;88;85 09:38:27 INFO - PROCESS | 1975 | |6;imaging-darkroom;94;96;94;97;95;96;95;96;95;94 09:38:27 INFO - PROCESS | 1975 | |7;imaging-desaturate;92;92;91;90;94;93;92;94;96;91 09:38:27 INFO - PROCESS | 1975 | |8;json-parse-financial;56;56;55;56;55;56;58;64;57;55 09:38:27 INFO - PROCESS | 1975 | |9;json-stringify-tinderbox;68;111;66;72;79;74;71;70;69;71 09:38:27 INFO - PROCESS | 1975 | |10;stanford-crypto-aes;88;92;80;87;87;86;86;88;86;85 09:38:27 INFO - PROCESS | 1975 | |11;stanford-crypto-ccm;117;128;138;143;147;136;141;132;128;147 09:38:27 INFO - PROCESS | 1975 | |12;stanford-crypto-pbkdf2;147;141;129;177;180;140;142;142;141;132 09:38:27 INFO - PROCESS | 1975 | |13;stanford-crypto-sha256-iterative;67;70;66;67;69;67;69;66;68;68 09:38:27 INFO - PROCESS | 1975 | __end_tp_report 09:38:27 INFO - PROCESS | 1975 | __start_cc_report 09:38:27 INFO - PROCESS | 1975 | _x_x_mozilla_cycle_collect,975 09:38:27 INFO - PROCESS | 1975 | __end_cc_report 09:38:27 INFO - PROCESS | 1975 | __startTimestamp1460047107426__endTimestamp 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | ------- Summary: start ------- 09:38:27 INFO - PROCESS | 1975 | Number of tests: 14 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#0] ai-astar Cycles:10 Average:113.30 Median:114.50 stddev:7.92 (6.9%) stddev-sans-first:8.39 09:38:27 INFO - PROCESS | 1975 | Values: 114.0 105.0 114.0 116.0 114.0 130.0 113.0 113.0 115.0 99.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:145.00 stddev:16.50 (11.4%) stddev-sans-first:17.04 09:38:27 INFO - PROCESS | 1975 | Values: 152.0 127.0 165.0 158.0 121.0 138.0 138.0 125.0 161.0 127.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#2] audio-dft Cycles:10 Average:200.60 Median:200.00 stddev:13.82 (6.9%) stddev-sans-first:14.38 09:38:27 INFO - PROCESS | 1975 | Values: 193.0 184.0 198.0 201.0 199.0 205.0 235.0 195.0 206.0 190.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#3] audio-fft Cycles:10 Average:76.80 Median:67.50 stddev:22.11 (32.8%) stddev-sans-first:23.16 09:38:27 INFO - PROCESS | 1975 | Values: 67.0 98.0 83.0 73.0 59.0 59.0 68.0 130.0 64.0 67.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:81.00 stddev:22.22 (27.4%) stddev-sans-first:23.04 09:38:27 INFO - PROCESS | 1975 | Values: 81.0 89.0 134.0 81.0 79.0 82.0 123.0 92.0 66.0 116.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.00 stddev:2.50 (2.8%) stddev-sans-first:2.65 09:38:27 INFO - PROCESS | 1975 | Values: 88.0 86.0 87.0 85.0 89.0 86.0 90.0 93.0 88.0 85.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.50 stddev:1.03 (1.1%) stddev-sans-first:1.00 09:38:27 INFO - PROCESS | 1975 | Values: 94.0 96.0 94.0 97.0 95.0 96.0 95.0 96.0 95.0 94.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#7] imaging-desaturate Cycles:10 Average:92.50 Median:92.50 stddev:1.78 (1.9%) stddev-sans-first:1.88 09:38:27 INFO - PROCESS | 1975 | Values: 92.0 92.0 91.0 90.0 94.0 93.0 92.0 94.0 96.0 91.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:56.00 stddev:2.70 (4.8%) stddev-sans-first:2.85 09:38:27 INFO - PROCESS | 1975 | Values: 56.0 56.0 55.0 56.0 55.0 56.0 58.0 64.0 57.0 55.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#9] json-stringify-tinderbox Cycles:10 Average:75.10 Median:71.00 stddev:13.10 (18.5%) stddev-sans-first:13.64 09:38:27 INFO - PROCESS | 1975 | Values: 68.0 111.0 66.0 72.0 79.0 74.0 71.0 70.0 69.0 71.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:87.00 stddev:2.99 (3.4%) stddev-sans-first:3.12 09:38:27 INFO - PROCESS | 1975 | Values: 88.0 92.0 80.0 87.0 87.0 86.0 86.0 88.0 86.0 85.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:139.50 stddev:9.57 (6.9%) stddev-sans-first:7.38 09:38:27 INFO - PROCESS | 1975 | Values: 117.0 128.0 138.0 143.0 147.0 136.0 141.0 132.0 128.0 147.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.10 Median:142.00 stddev:17.35 (12.2%) stddev-sans-first:18.40 09:38:27 INFO - PROCESS | 1975 | Values: 147.0 141.0 129.0 177.0 180.0 140.0 142.0 142.0 141.0 132.0 09:38:27 INFO - PROCESS | 1975 | 09:38:27 INFO - PROCESS | 1975 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.34 (2.0%) stddev-sans-first:1.39 09:38:27 INFO - PROCESS | 1975 | Values: 67.0 70.0 66.0 67.0 69.0 67.0 69.0 66.0 68.0 68.0 09:38:27 INFO - PROCESS | 1975 | -------- Summary: end -------- 09:38:27 INFO - PROCESS | 1975 | 09:38:28 INFO - TEST-INFO | 1975: exit 0 09:38:28 INFO - TEST-OK | kraken | took 57859ms 09:38:28 INFO - SUITE-END | took 1169s 09:38:28 INFO - Completed test suite (00:19:29) 09:38:28 INFO - javascript benchmark 09:38:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3399.490076488528, 3484.3205574912895, 3490.581130264368, 3531.699805516531, 3589.2283159120784, 5822.155787410828, 5830.766769329232, 5840.620359676618, 5856.531477481802, 5874.676892770901, 5699.857503562413, 5805.622634528752, 5815.709214539267, 5898.705064746756, 5958.106284057393, 5192.480751924815, 5247.842564723045, 5262.605304602151, 5289.391719952197, 5308.575313974892, 347.1748161728853, 5267.051930652481, 5270.130428479296, 5293.100172970604, 5312.229726689628, 5206.713630750307, 5224.817131400383, 5248.186531087668, 5260.789568417259, 5283.580608346144, 5155.9742201288755, 5290.735463226823, 5298.09932311508, 5317.468253174651, 5322.707251101227, 5171.689698618095, 5175.171972484422, 5227.817026404057, 5274.129768588178, 5306.973465132688, 5578.30271216098, 5628.634138780966, 5671.943280567224, 5681.261436013333, 5712.028955077605, 6158.291796443422, 6197.411245931629, 6229.626222426669, 6266.216722909637, 6281.811545653638, 6214.40963108504, 6277.686115694197, 6283.151774510428, 6297.086922396227, 6307.400796924285, 5967.313758917693, 5988.281406231236, 5989.348302571676, 5993.970030149865, 6037.9396206038255, 5687.289088863892, 6013.6692481913915, 6279.591826531261, 6316.873662526724, 6326.177596912372, 5168.586513078944, 6242.781502647476, 6243.188385509855, 6255.155554000243, 6264.874702505925, 6003.489703375175, 6241.906371404432, 6281.371862813773, 6305.3379395163765, 6312.5581209314905, 4526.072155208174, 5182.222666600041, 5965.612235204698, 6103.580118178676, 6150.907736383958, 5027.497250274943, 6077.574569780161, 6259.1550140730415, 6260.467860231931, 6276.6547839868335, 4893.657443978958, 4940.333055037596, 4958.454569997298, 4958.553730164231, 4994.550490455877, 4398.318260669537, 4402.3176407656865, 4500.752458614807, 4510.030343476168, 4536.092781443725, 5489.3687225969525, 5789.739461724233, 5867.735951882176, 5869.501092407193, 5907.084401917709, 6291.779787707408, 6298.0552917062805, 6313.621182729051, 6324.2410910691015, 6338.809835704937, 5755.59710820247, 5836.212111364912, 5844.298684157929, 5888.263967004124, 5916.556258280646, 4968.882001549622, 5433.0763770159165, 5441.183822426668, 5462.890742185214, 5471.589630777628, 6243.282022567372, 6263.154474145932, 6463.15978922737, 6472.935270647234, 6483.837904052342, 6189.566730328834, 6224.470919971853, 6272.5922815016875, 6285.214348206474, 6289.779857704958, 6419.518536109717, 6424.678766061772, 6442.677866106769, 6452.387023232785, 6456.677166141768, 6059.697015149313, 6147.108669242845, 6170.845728856814, 6213.254409470893, 6227.283862355887, 6196.783112591038, 6245.750169993149, 6255.687215639109, 6269.749210031548, 6271.811845644638, 6131.356207598138, 6145.569810113249, 6159.414855588712, 6167.814965551041, 6180.907286390618, 5951.851203719942, 6076.787312444043, 6140.565599444434, 6154.353792851686, 6162.876742465036, 5906.291245050621, 5963.552733544915, 5981.371955944737, 6125.989211780185, 6126.548209435115, 6111.816645500642, 6172.845678858064, 6176.289726681489, 6184.257889053342, 6198.938010620016, 6035.18524999137, 6066.636001839904, 6103.816885493442, 6124.050772130327, 6132.509399248139], "unit": "score", "name": "dojo.html", "value": 5693.631878146678}, {"lowerIsBetter": false, "replicates": [446.0476881857236, 570.2899889637403, 606.940888150178, 607.1560530862102, 623.5759683415278, 35313.293734125305, 38858.417123743166, 39307.0, 39384.99999999996, 39397.80301098498, 40942.59057409425, 40957.38563921536, 41288.0, 41616.0, 41631.79184104083, 9487.28845336599, 9620.855687164698, 9627.951860240724, 9646.0, 9763.560639771193, 7768.262015108556, 8673.959124904988, 9599.808003839918, 9609.231261499099, 9635.614575416976, 9569.760755981086, 9582.52087395631, 9614.80770384592, 9629.133377995979, 9638.903610963871, 9532.95233523828, 9536.094071063204, 9543.761405964871, 9584.808303833885, 9618.038196180396, 8252.133525979805, 9454.243660507143, 9463.763405914908, 9584.424934503882, 9628.18160456362, 30836.074917752503, 38279.23441531154, 39584.6145384913, 39700.60299396998, 39880.002999924945, 2598.5712357461, 2823.1671656861276, 2916.839573823439, 3138.3880143372053, 3448.0, 27642.0, 28630.997915072872, 28693.28266793326, 29086.27284317909, 29397.412051758845, 2640.7095219525836, 2711.8644067796727, 2717.0897749253827, 2722.233221144336, 2811.437712457496, 372125.0, 372534.0, 375160.12419937726, 377056.0, 377800.0, 46698.76650616725, 47182.0, 47185.0, 47577.76211118922, 62934.68532657399, 45259.547404526194, 46026.53973460222, 46041.0, 46435.76782116135, 46460.53539464562, 15957.760633590506, 15987.44043958456, 16166.83833161677, 16175.838241617668, 16181.757273640906, 12620.747585048248, 12820.423080961193, 12863.549775757803, 12939.0, 13049.151805132637, 14842.332095055748, 14943.925280373529, 15010.549683509513, 15061.849381506045, 15299.694006119815, 5361.785528578891, 6233.3143354231, 6280.340564240689, 6345.936540634534, 6347.428731414195, 918.4535201555065, 966.7969726357544, 968.874046373981, 974.7563109222694, 1039.0648416425279, 25915.74084259171, 28318.442485663076, 29582.112536623936, 29762.0, 29974.0, 1070.2829104500004, 1097.3415950429678, 1119.9552017919193, 1132.1282612388416, 1137.4199158429096, 15161.772573411408, 15289.388424463119, 15339.079655220723, 15404.61488462775, 15540.0, 507473.0, 508062.0, 512861.0, 516591.0, 526449.0, 59000.0, 59288.70355648194, 60091.0, 60206.0, 60523.0, 1405.325443786988, 1417.7660685986903, 1434.2470203143225, 1448.6523234423873, 1458.7593047147313, 25385.207627160627, 27724.8613756938, 29635.703642964156, 30195.396092078474, 30321.848390757907, 45393.86515337143, 52123.739381302854, 53154.73422632862, 53400.73299633477, 53645.0, 414763.0, 476276.0, 476988.0, 477423.0, 478314.0, 34411.311773764886, 44754.3286850704, 45688.08623827439, 45873.85315367009, 46719.0, 34543.13642158966, 42872.571274286856, 43266.918327042076, 44327.778361109275, 44414.3337849939, 30682.693173067983, 34565.48151777675, 38205.0, 38551.6144838548, 39449.80275098606, 30943.690563094082, 36334.90995270146, 38514.80742596269, 39802.20395592014, 39844.80077599594, 33715.0, 35551.82224088863, 36959.815200923826, 37262.06844828901, 39879.0, 32926.83536582398, 33433.49849752207, 33991.98024059282, 34185.658143418244, 34375.656243437246, 20755.584888302452, 24807.354467778652, 25817.741822581535, 25968.961241550125, 26015.73984260133], "unit": "score", "name": "ext.html", "value": 18988.305271515852}, {"lowerIsBetter": false, "replicates": [303.8890804856228, 312.85495087978006, 333.33000668656, 353.7735849056606, 356.61129368987804, 19289.421317360484, 19775.802241977577, 20483.0, 20495.385138445872, 20677.483062923417, 16889.057758357794, 19977.901215433147, 20836.791632083674, 21029.0, 21060.262890798826, 5430.0, 5645.379008309083, 5697.8005769798065, 5722.113072473752, 5754.539636829044, 5518.117101263799, 5794.362620111784, 5813.883722325551, 5864.384239654839, 5941.0494320908665, 5795.15970184323, 5817.389174136718, 5821.738021789009, 5827.184194212792, 5848.327442344143, 5525.254090697745, 5659.151127330933, 5672.829815105553, 5764.07774756037, 5813.215215945836, 4918.188498897677, 4928.778204980785, 4971.254311853215, 5005.74971251436, 5022.874428139326, 24391.87804060986, 24433.022679092814, 24785.504289914283, 25044.373890652696, 25141.748582514123, 2094.539201375695, 2113.9894300528554, 2162.329677799871, 2225.477012901983, 2256.4697296135396, 13991.950603704763, 14339.28303584827, 14685.265736713229, 14734.0, 14825.258737063104, 1638.4265507072548, 1650.3811070848433, 1700.846923776866, 1716.3306310538899, 1738.0961899812028, 66613.0, 81155.18844811535, 85834.0, 88453.0, 89500.0, 10255.897441025643, 10311.535980880879, 10380.584776608852, 10405.115565177044, 10498.685039448828, 12567.0, 12626.0, 12638.178518396275, 12642.810357844639, 12762.830318845818, 8382.748517544484, 8405.033421156522, 8546.487210767375, 8594.097619749962, 8638.91361086381, 8793.91206087931, 8832.38173327861, 8859.158379953895, 8874.156955089256, 8889.377743558018, 5900.232969713909, 5988.101784732325, 6005.549583781147, 6009.248843894513, 6114.449699527064, 1586.50818246344, 1781.0916432619458, 1814.0838876367613, 1816.7910690270523, 1818.7180986947044, 872.2193636695249, 946.654471118037, 971.3006635222629, 1003.9408424112631, 1057.841323801436, 16354.591135221477, 19131.23475060982, 20206.0, 20686.68969965452, 20863.79136208649, 887.8179973105498, 911.3984770051734, 953.9856902146473, 955.4124213608719, 993.9304248702522, 10034.24743144267, 10303.793924121475, 10509.369437833755, 10547.419891905865, 10577.62978295756, 606015.0, 609272.9536352379, 618047.0, 618665.9066704637, 628435.0, 112801.0, 119554.81558145423, 120642.0, 122440.0, 122466.0, 1009.0766948242423, 1012.2509343086064, 1024.8718910136233, 1043.8121138195047, 1048.8898665639997, 15392.153431561372, 16715.58211044685, 18307.63384732271, 18613.813861861207, 18629.06854657289, 47223.0, 49337.75331123322, 49949.75025124851, 52661.0, 53016.2047569279, 50272.74863625658, 51991.0, 66726.66636666785, 67729.0, 68779.65610172118, 6669.264043316189, 6727.966360168168, 7544.8491030180185, 7719.922800772145, 7976.7208147714555, 8819.514926678838, 9338.533073346442, 9529.285303602397, 11394.316341019565, 12449.128561001005, 10223.591056357662, 10574.947125264633, 10586.629467968583, 11044.668659940215, 11137.387443690675, 8424.5787710613, 10413.47932603382, 10450.42522661261, 10833.729156771144, 10885.836712449161, 10868.347899126078, 11174.217804753915, 11259.831102533632, 11262.605808796981, 11274.379909105079, 7549.50928189666, 7669.233076692286, 7801.687932482864, 7881.330086942674, 8012.078610959814, 8121.553314567519, 10263.973602639677, 10823.729406764895, 10865.0, 11158.44207789591], "unit": "score", "name": "jquery.html", "value": 9177.440032310644}, {"lowerIsBetter": false, "replicates": [1585.0093691442848, 1596.6567188054566, 1869.1588785046733, 1944.9513762155943, 1968.507873031742, 7904.6442910069045, 8589.570521473917, 8627.913720862789, 8857.11428857114, 8869.866951995717, 8454.492730436163, 8508.191721786436, 8589.87115193271, 8696.434731742433, 8699.65201391945, 2470.1231062972656, 2488.9377765555914, 2511.635812807142, 2513.45960618467, 2532.4175439648893, 2375.679283296759, 2431.209856796548, 2448.9142879999204, 2459.4220358215807, 2466.5930121529927, 2479.950400991979, 2494.725580186178, 2496.400863792686, 2526.835755675875, 2530.101813856082, 2435.8538487690707, 2447.3269850791175, 2450.4486490539484, 2483.242611003643, 2512.560301947161, 2469.4567195217205, 2478.8140889433366, 2498.337940445765, 2498.400383907849, 2501.9866953883525, 8775.210231079169, 8807.163319484704, 8819.206271435538, 8832.646694132227, 8842.159994800548, 5418.0789265824515, 5425.294711687495, 5493.176023596453, 5547.639403438763, 5576.856744367395, 3546.627604101558, 3623.547056617923, 3666.926661466782, 3671.155634204148, 3673.3571624965793, 804.6097642643314, 1004.1514919892679, 1020.6070662794878, 1028.0336483705294, 1049.7375656085978, 11562.826557601558, 11739.941300293529, 11744.060475161967, 11951.103667224992, 11970.401479926057, 4336.956630433655, 4746.1694203514935, 4848.684835485682, 4907.263910413467, 5047.965167140727, 9223.308251881135, 9245.953770231241, 9271.304652151115, 9337.90662093384, 9382.718518444457, 4412.889677758082, 5352.411234764173, 5360.544353729977, 5395.678058875601, 5396.892062158735, 4289.871303860889, 4342.478902531717, 4831.444383895827, 4907.877303067452, 4929.926051109236, 3092.38152369527, 3692.132348898007, 3733.738638295293, 3772.6227377262603, 3781.8487260509273, 1179.2511755035614, 1186.2289511817214, 1192.1714408486193, 1196.7367179220557, 1199.8140288255333, 1339.7923321885123, 1355.105630284008, 1380.813590165335, 1386.4592808804373, 1400.4888215801363, 7216.737071012657, 8078.919210807934, 8173.877391839127, 8361.0, 8372.58137093143, 510.64255021484365, 515.443321213089, 520.4639221601756, 520.6225486522317, 524.4231345519974, 6159.076138579159, 6169.0, 6219.284782250009, 6224.0, 6234.127222188898, 10464.790704186025, 10750.94624526888, 10880.727981800519, 10926.508307126198, 10981.890181098086, 10454.425006624557, 10592.523336449878, 10737.731556711145, 10819.242653014371, 10833.133349332034, 8296.045954715284, 8318.251357377743, 8373.07896131425, 8402.831943361221, 8457.53483558398, 7815.609219539114, 7912.604369781373, 7988.081370642218, 8057.959710201411, 8148.22591853773, 9370.812583748151, 9371.0, 9661.71014869555, 9789.46157961319, 9822.116009559173, 9726.319157658896, 9812.901870981199, 10058.19534437258, 10163.644272450429, 10263.0, 6321.8735625288155, 6690.899636505554, 6981.930180698128, 7011.859762804817, 7134.964325178341, 8246.670133194844, 8285.875711864206, 8333.874926885035, 8334.6666133354, 8335.49987000782, 7224.74713385029, 7325.816854578465, 7406.888896666662, 7440.516366436164, 7518.473706840688, 7262.346388825031, 7275.8181045472165, 7305.977163197051, 7323.304286092813, 7351.228121047213, 7712.267334603203, 7778.649960751893, 7810.218978102144, 7822.335101516434, 7824.608769561613, 6780.254172040973, 6920.7231710731, 6959.8608027838145, 6986.476014298846, 7083.1854336749875, 7065.894011589728, 7075.150981882207, 7130.144382674112, 7130.714771409085, 7157.997880296659], "unit": "score", "name": "mootools.html", "value": 4810.306396870392}, {"lowerIsBetter": false, "replicates": [271.55329483000463, 329.10647591788296, 332.3834287396877, 337.7669408108405, 340.54537192847545, 6755.5271131020845, 6842.521023528355, 6893.241743408234, 6913.965430172855, 6940.930590694085, 6682.565633233837, 6754.560953538016, 6829.6926638301275, 6847.623380714059, 6849.554778939366, 4726.503717109706, 4867.853964381056, 4977.5022497750115, 4978.352814134158, 5010.448850626437, 3729.0, 4957.405111386639, 5011.799528018875, 5031.52200540948, 5052.0906236877345, 4969.329140566031, 4976.4774698656665, 4980.6762560433635, 4989.825356112537, 5021.171506701408, 4996.1756310208775, 5000.399952005764, 5003.249512573125, 5004.524570165829, 5023.874403139914, 4293.785310734451, 4295.646871235551, 4300.0, 4308.073764140693, 4314.697971142022, 4915.778789954461, 5510.421405752379, 6364.835955775059, 6474.158359413246, 6491.740330386779, 5129.9483605860705, 5806.680632565207, 5893.0276504376725, 5938.346781854036, 5959.910601340984, 5806.65160090396, 7140.7857764267155, 7253.746118885866, 7277.599732014737, 7300.233475485051, 4768.308595253703, 4829.637777166727, 4829.806807727687, 4830.686005409672, 4845.975770121162, 6097.756089756404, 7609.201033891472, 7674.117476490163, 7682.385409167309, 7700.768976930701, 4705.623550116051, 4868.050730107666, 4906.730129842893, 4912.631552633567, 4921.827736029221, 4975.203967365204, 5000.924801167766, 5009.699418034929, 5010.824621138243, 5015.6489045767175, 5797.884042319214, 5921.7631294747725, 5922.733476993546, 5934.13954976526, 5949.167116603679, 7278.797329175146, 7282.927170728225, 7295.744648937261, 7297.525660832029, 7318.634068296564, 1717.9742303865453, 1747.2137538107902, 1751.3695069774872, 1755.8419742223261, 1756.8067512573607, 1071.2929466552193, 1118.6532175025768, 1121.8429419881222, 1126.6282126898104, 1142.0749193153572, 6559.278479367265, 6626.105475760807, 6670.866582668319, 6677.699503522354, 6679.966600167065, 6035.305939816977, 7544.8491030180185, 7550.509216900884, 7553.395728341719, 7573.939648449222, 6449.451796597342, 6563.0, 6743.224529179109, 6743.730250789913, 6775.288594697585, 3333.8833140839956, 3979.7015223857748, 4025.6175663311938, 4048.5344185418467, 4064.3700226464944, 6010.9699451502465, 6472.611643301323, 7869.960650196713, 7977.7207797726805, 8012.679492820338, 7429.5542267464125, 7880.3301719354495, 7894.763157105296, 7905.486143400775, 7926.365890728728, 6483.384078512534, 7900.170482099296, 7949.205079492121, 7958.761237162895, 7963.482373645695, 7560.6219689016425, 7604.8479030417975, 7612.429067820047, 7634.083909930844, 7635.083789945242, 7421.851562968818, 7426.962865185639, 7431.331180193809, 7466.216047315171, 7467.92532074694, 6776.864462710817, 7483.251674832473, 7900.486468379537, 7907.644156013108, 7942.523448593103, 4261.083866968616, 4277.593628605277, 4611.0547337795, 4640.907181856412, 4689.015306785477, 7291.869760187179, 7516.098068231847, 7542.924570754222, 7555.282248186413, 7563.281488258606, 5447.4824891635235, 5514.6691198528215, 7289.635518223961, 7299.05112335393, 7301.379382752525, 6993.755218567326, 7283.344498995116, 7304.780856574312, 7318.524295920748, 7329.670164842488, 5743.368229494668, 7189.137303523611, 7199.496035277481, 7206.89189662145, 7220.241874603301, 5738.655680659007, 5963.642181469126, 7193.884947833094, 7362.7423040193335, 7378.188399276182, 7126.930960355988, 7147.249538798351, 7158.928410715826, 7161.677724502514, 7204.819879502845], "unit": "score", "name": "prototype.html", "value": 5244.473166910619}, {"lowerIsBetter": false, "replicates": [659.6141257364442, 683.624006796262, 739.9001134846795, 755.773268019594, 758.8596109719706, 14374.49689260876, 14433.566992990227, 14570.635734106652, 14573.927130364347, 14593.708125837491, 15564.53306400807, 15812.209389530506, 15853.207339633002, 15861.444849430272, 15877.920610396961, 804.1355542791493, 827.5435233988194, 894.6466145872377, 899.2401420799447, 900.459724165502, 721.7437809577604, 885.3359980014989, 900.5722281916103, 900.8603666431713, 902.823949329881, 939.178219058324, 940.5109343141562, 942.3074040580168, 943.7310366545536, 944.2115833279186, 760.9923900760976, 923.1875949164726, 928.2620316848122, 936.7751739582485, 939.5067589515525, 676.7529851604178, 677.16708448608, 690.7547820523693, 698.3016983016983, 698.559907258429, 14365.497207597684, 14631.70736585273, 14691.11853288806, 14716.632084197874, 14790.704185916222, 12157.148999570034, 12399.0, 12422.875771242352, 12580.4338804754, 12629.2422454653, 9975.551100200508, 10055.446950417723, 10063.748406289827, 10125.139363154141, 10461.372317660966, 3425.0409885232175, 3492.9650703492894, 3494.9825250873837, 3561.465780132975, 3627.2201476682376, 79433.0, 80027.7995830063, 80692.3861522772, 80962.0, 81119.0, 8792.8681069784, 8879.023307436177, 8950.552472376356, 8985.46087234768, 9101.863472047924, 20063.598728025358, 20703.0, 20750.06624701892, 20756.68864967027, 20770.58458830845, 7321.414286857039, 7328.9267107329315, 7333.193348731742, 7340.632968351668, 7421.962890185622, 4086.141910198832, 4128.896777580585, 4134.834606615762, 4140.0891803803115, 4141.7722025288895, 3485.3203625292826, 3497.0208341664375, 3536.911577210539, 3559.3059353425833, 3573.12458447682, 634.8222497700651, 645.686841881687, 645.7707513832615, 650.4503694378243, 658.3613894522306, 457.3574128349674, 467.2430662327052, 584.95612829038, 585.3842452263845, 592.4371846745523, 7787.221277872168, 7963.163867793798, 8186.836263274701, 8242.464239824392, 8423.115572864883, 467.78949472737406, 512.8743457852776, 532.9680219186861, 554.9649902931004, 586.369652623423, 3772.5472943246987, 3826.253880493333, 3857.9421308680394, 3864.662177965515, 3910.765354078764, 74879.90082132368, 83323.33353332899, 84773.0, 86149.13850861411, 94296.52851735834, 77949.0, 79149.60425197953, 79836.0, 79872.0, 80574.0, 70198.64900675464, 70290.0, 70722.0, 71233.64383178052, 71812.64093679603, 7607.961960190164, 8463.57682115904, 8465.746027619181, 8514.31885449175, 8595.140485951355, 49787.75106124446, 50223.49776502188, 51548.226776599135, 52006.479935200165, 52501.21248181357, 64195.67902160459, 69834.0, 70954.64522677353, 71050.0, 71382.0, 10634.73413164677, 10967.396793176453, 11111.444427778417, 11175.27360721583, 11347.432628368713, 11447.198696091195, 11745.765084698429, 12150.270983741004, 12255.019598431925, 12557.560485382968, 11287.66137015858, 11384.260023098472, 11442.0, 11708.297502149899, 11881.465334060162, 8438.957805210935, 11229.3262404256, 11295.830562541732, 11297.43512824372, 11475.885241147482, 10967.561297548327, 11116.16628752863, 11153.944230278797, 11243.887561124284, 11575.073994080285, 7317.085364329459, 9206.355555111079, 9261.212796912338, 9486.0, 9581.233501320017, 9126.771830704285, 9477.0, 9500.809983800147, 9515.714528564155, 9549.379290346105], "unit": "score", "name": "yui.html", "value": 6803.417750583557}], "name": "dromaeo_css", "value": 7445.125925992378}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 105.0, 114.0, 116.0, 114.0, 130.0, 113.0, 113.0, 115.0, 99.0], "unit": "score", "name": "ai-astar", "value": 113.3}, {"lowerIsBetter": true, "replicates": [152.0, 127.0, 165.0, 158.0, 121.0, 138.0, 138.0, 125.0, 161.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 141.2}, {"lowerIsBetter": true, "replicates": [193.0, 184.0, 198.0, 201.0, 199.0, 205.0, 235.0, 195.0, 206.0, 190.0], "unit": "score", "name": "audio-dft", "value": 200.6}, {"lowerIsBetter": true, "replicates": [67.0, 98.0, 83.0, 73.0, 59.0, 59.0, 68.0, 130.0, 64.0, 67.0], "unit": "score", "name": "audio-fft", "value": 76.8}, {"lowerIsBetter": true, "replicates": [81.0, 89.0, 134.0, 81.0, 79.0, 82.0, 123.0, 92.0, 66.0, 116.0], "unit": "score", "name": "audio-oscillator", "value": 94.3}, {"lowerIsBetter": true, "replicates": [88.0, 86.0, 87.0, 85.0, 89.0, 86.0, 90.0, 93.0, 88.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 87.7}, {"lowerIsBetter": true, "replicates": [94.0, 96.0, 94.0, 97.0, 95.0, 96.0, 95.0, 96.0, 95.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 95.2}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 91.0, 90.0, 94.0, 93.0, 92.0, 94.0, 96.0, 91.0], "unit": "score", "name": "imaging-desaturate", "value": 92.5}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 55.0, 56.0, 55.0, 56.0, 58.0, 64.0, 57.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 56.8}, {"lowerIsBetter": true, "replicates": [68.0, 111.0, 66.0, 72.0, 79.0, 74.0, 71.0, 70.0, 69.0, 71.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 75.1}, {"lowerIsBetter": true, "replicates": [88.0, 92.0, 80.0, 87.0, 87.0, 86.0, 86.0, 88.0, 86.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.5}, {"lowerIsBetter": true, "replicates": [117.0, 128.0, 138.0, 143.0, 147.0, 136.0, 141.0, 132.0, 128.0, 147.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 135.7}, {"lowerIsBetter": true, "replicates": [147.0, 141.0, 129.0, 177.0, 180.0, 140.0, 142.0, 142.0, 141.0, 132.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 147.1}, {"lowerIsBetter": true, "replicates": [67.0, 70.0, 66.0, 67.0, 69.0, 67.0, 69.0, 66.0, 68.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.7}], "name": "kraken", "value": 1470.5}]} 09:38:28 INFO - Return code: 0 09:38:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:38:28 ERROR - # TBPL SUCCESS # 09:38:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:28 INFO - 2016-04-07 09:38:28.715 screenresolution[1980:14048] starting screenresolution argv=screenresolution get 09:38:28 INFO - 2016-04-07 09:38:28.717 screenresolution[1980:14048] Display 0: 1600x1200x32@60 09:38:28 INFO - 2016-04-07 09:38:28.723 screenresolution[1981:14051] starting screenresolution argv=screenresolution list 09:38:28 INFO - Available Modes on Display 0 09:38:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:28 INFO - Intel Iris: 09:38:28 INFO - Chipset Model: Intel Iris 09:38:28 INFO - Type: GPU 09:38:28 INFO - Bus: Built-In 09:38:28 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:28 INFO - Vendor: Intel (0x8086) 09:38:28 INFO - Device ID: 0x0a2e 09:38:28 INFO - Revision ID: 0x0009 09:38:28 INFO - Displays: 09:38:28 INFO - Display: 09:38:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:28 INFO - Display Serial Number: 1600x1200 60 09:38:28 INFO - Main Display: Yes 09:38:28 INFO - Mirror: Off 09:38:28 INFO - Online: Yes 09:38:28 INFO - Rotation: Supported 09:38:28 INFO - Return code: 0 09:38:28 INFO - Running post-action listener: _resource_record_post_action 09:38:28 INFO - Running post-run listener: _resource_record_post_run 09:38:30 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 167936; Write bytes: 694058496; Read time: 6; Write time: 1893 09:38:30 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 379572736; Write bytes: 373585408; Read time: 28040; Write time: 680 09:38:30 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 163840; Write bytes: 298813440; Read time: 5; Write time: 1168 09:38:30 INFO - Running post-run listener: _upload_blobber_files 09:38:30 INFO - Blob upload gear active. 09:38:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:30 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:30 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:30 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:30 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:38:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:38:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:31 INFO - (blobuploader) - INFO - Done attempting. 09:38:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:38:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:38:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:32 INFO - (blobuploader) - INFO - Done attempting. 09:38:32 INFO - (blobuploader) - INFO - Iteration through files over. 09:38:32 INFO - Return code: 0 09:38:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:38:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:38:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e5364cfdf1066dff5bc6ef643cd02fbd2d90c5193be5bae575b42d147a292af340a418ee70e389743541a1eaa201db20b2a8eab17d44f6b734578c0cd051a00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f0b691ed3773e10b5313352f1b5487c20d897f8b1634b8f2f93ff8721186cf2c9654ccce44904e2299d62d007c03961860fee231fd3cc9793c8222f9f5f9b1b"} 09:38:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:38:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:38:32 INFO - Contents: 09:38:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e5364cfdf1066dff5bc6ef643cd02fbd2d90c5193be5bae575b42d147a292af340a418ee70e389743541a1eaa201db20b2a8eab17d44f6b734578c0cd051a00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f0b691ed3773e10b5313352f1b5487c20d897f8b1634b8f2f93ff8721186cf2c9654ccce44904e2299d62d007c03961860fee231fd3cc9793c8222f9f5f9b1b"} 09:38:32 INFO - Running post-run listener: copy_logs_to_upload_dir 09:38:32 INFO - Copying logs to upload dir... 09:38:32 INFO - mkdir: /builds/slave/test/build/upload/logs 09:38:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.898708 ========= master_lag: 5.31 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-04-07 09:38:37.973231) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-07 09:38:37.976343) ========= 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.LvURJh4dmx/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.176pnwAAZL/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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/8e5364cfdf1066dff5bc6ef643cd02fbd2d90c5193be5bae575b42d147a292af340a418ee70e389743541a1eaa201db20b2a8eab17d44f6b734578c0cd051a00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f0b691ed3773e10b5313352f1b5487c20d897f8b1634b8f2f93ff8721186cf2c9654ccce44904e2299d62d007c03961860fee231fd3cc9793c8222f9f5f9b1b"} 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.011064 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/8e5364cfdf1066dff5bc6ef643cd02fbd2d90c5193be5bae575b42d147a292af340a418ee70e389743541a1eaa201db20b2a8eab17d44f6b734578c0cd051a00", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7f0b691ed3773e10b5313352f1b5487c20d897f8b1634b8f2f93ff8721186cf2c9654ccce44904e2299d62d007c03961860fee231fd3cc9793c8222f9f5f9b1b"}' ========= master_lag: 8.73 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-07 09:38:46.717547) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:38:46.718161) ========= 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.LvURJh4dmx/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.176pnwAAZL/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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.005345 ========= master_lag: 0.32 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:38:47.039726) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:38:47.040085) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:38:47.042023) ========= ========= Total master_lag: 14.66 =========