builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0162 starttime: 1460045837.27 results: success (0) buildid: 20160407090709 builduid: aa98a138246e4406a86436be73cb852c revision: 55f0de142be46043246b18108299aebf2bd95a64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.279050) ========= 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:17.279545) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.279891) ========= 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.qF7270s3e2/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.QRZsvTneCo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.005632 basedir: '/builds/slave/test' ========= master_lag: 0.13 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.415136) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.415582) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.434747) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.435096) ========= 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.qF7270s3e2/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.QRZsvTneCo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.005367 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.488462) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.488768) ========= 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:17.489179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.489492) ========= 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.qF7270s3e2/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.QRZsvTneCo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.7M=0.001s 2016-04-07 09:17:17 (15.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.175918 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.681830) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.682277) ========= 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.qF7270s3e2/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.QRZsvTneCo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.020629 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:17.730819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-07 09:17:17.731200) ========= 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.qF7270s3e2/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.QRZsvTneCo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:17,807 truncating revision to first 12 chars 2016-04-07 09:17:17,807 Setting DEBUG logging. 2016-04-07 09:17:17,807 attempt 1/10 2016-04-07 09:17:17,807 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:19,964 unpacking tar archive at: fx-team-55f0de142be4/testing/mozharness/ program finished with exit code 0 elapsedTime=2.404483 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-07 09:17:20.153145) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:20.153547) ========= 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.qF7270s3e2/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.QRZsvTneCo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.005174 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:20.204783) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:20.205161) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:17:20.221193) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-04-07 09:17:20.221618) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qF7270s3e2/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.QRZsvTneCo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:20 INFO - MultiFileLogger online at 20160407 09:17:20 in /builds/slave/test 09:17:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:17:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:20 INFO - {'append_to_log': False, 09:17:20 INFO - 'base_work_dir': '/builds/slave/test', 09:17:20 INFO - 'blob_upload_branch': 'Fx-Team', 09:17:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:20 INFO - 'branch': 'Fx-Team', 09:17:20 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:20 INFO - 'config_files': ('talos/mac_config.py',), 09:17:20 INFO - 'default_actions': ('clobber', 09:17:20 INFO - 'read-buildbot-config', 09:17:20 INFO - 'download-and-extract', 09:17:20 INFO - 'populate-webroot', 09:17:20 INFO - 'create-virtualenv', 09:17:20 INFO - 'install', 09:17:20 INFO - 'run-tests'), 09:17:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:20 INFO - 'download_minidump_stackwalk': True, 09:17:20 INFO - 'download_symbols': 'ondemand', 09:17:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:20 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:20 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:20 INFO - 'installer_path': 'installer.exe', 09:17:20 INFO - 'log_level': 'info', 09:17:20 INFO - 'log_name': 'talos', 09:17:20 INFO - 'log_to_console': True, 09:17:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:17:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:17:20 INFO - 'opt_config_files': (), 09:17:20 INFO - 'pip_index': False, 09:17:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:20 INFO - 'cmd': ('bash', 09:17:20 INFO - '-c', 09:17:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:20 INFO - 'enabled': True, 09:17:20 INFO - 'halt_on_failure': False, 09:17:20 INFO - 'name': 'check_screen_resolution'},), 09:17:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:20 INFO - 'cmd': ('bash', 09:17:20 INFO - '-c', 09:17:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:20 INFO - 'enabled': True, 09:17:20 INFO - 'halt_on_failure': False, 09:17:20 INFO - 'name': 'check_screen_resolution'},), 09:17:20 INFO - 'run_cmd_checks_enabled': True, 09:17:20 INFO - 'sps_profile': False, 09:17:20 INFO - 'sps_profile_interval': 0, 09:17:20 INFO - 'suite': 'dromaeojs', 09:17:20 INFO - 'system_bits': '32', 09:17:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:20 INFO - 'title': 't-yosemite-r7-0162', 09:17:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:20 INFO - 'use_talos_json': True, 09:17:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:20 INFO - 'work_dir': 'build'} 09:17:20 INFO - ##### 09:17:20 INFO - ##### Running clobber step. 09:17:20 INFO - ##### 09:17:20 INFO - Running pre-action listener: _resource_record_pre_action 09:17:20 INFO - Running main action method: clobber 09:17:20 INFO - rmtree: /builds/slave/test/build 09:17:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:21 INFO - Running post-action listener: _resource_record_post_action 09:17:21 INFO - ##### 09:17:21 INFO - ##### Running read-buildbot-config step. 09:17:21 INFO - ##### 09:17:21 INFO - Running pre-action listener: _resource_record_pre_action 09:17:21 INFO - Running main action method: read_buildbot_config 09:17:21 INFO - Using buildbot properties: 09:17:21 INFO - { 09:17:21 INFO - "project": "", 09:17:21 INFO - "product": "firefox", 09:17:21 INFO - "script_repo_revision": "production", 09:17:21 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:17:21 INFO - "repository": "", 09:17:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:17:21 INFO - "buildid": "20160407090709", 09:17:21 INFO - "stage_platform": "macosx64", 09:17:21 INFO - "basedir": "/builds/slave/test", 09:17:21 INFO - "buildnumber": 585, 09:17:21 INFO - "platform": "yosemite_r7", 09:17:21 INFO - "mozci_request": { 09:17:21 INFO - "priority": -1, 09:17:21 INFO - "type": "trigger_all_talos_jobs", 09:17:21 INFO - "times": 6 09:17:21 INFO - }, 09:17:21 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:17:21 INFO - "slavebuilddir": "test", 09:17:21 INFO - "branch": "fx-team", 09:17:21 INFO - "revision": "55f0de142be46043246b18108299aebf2bd95a64", 09:17:21 INFO - "slavename": "t-yosemite-r7-0162", 09:17:21 INFO - "builduid": "aa98a138246e4406a86436be73cb852c", 09:17:21 INFO - "repo_path": "integration/fx-team" 09:17:21 INFO - } 09:17:21 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:21 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:21 INFO - Running post-action listener: _resource_record_post_action 09:17:21 INFO - ##### 09:17:21 INFO - ##### Running download-and-extract step. 09:17:21 INFO - ##### 09:17:21 INFO - Running pre-action listener: _resource_record_pre_action 09:17:21 INFO - Running main action method: download_and_extract 09:17:21 INFO - mkdir: /builds/slave/test/build/tests 09:17:21 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:21 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:21 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:21 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:21 INFO - Downloaded 1199 bytes. 09:17:21 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:17:21 INFO - Using the following test package requirements: 09:17:21 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:17:21 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:21 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:17:21 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:21 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:17:21 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:21 INFO - u'jsshell-mac.zip'], 09:17:21 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:21 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:17:21 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:17:21 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:21 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:17:21 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:21 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:17:21 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:21 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:17:21 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:17:21 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:17:21 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:17:21 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:21 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:21 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:21 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:21 INFO - Downloaded 17970719 bytes. 09:17:21 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:21 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:25 INFO - Return code: 0 09:17:25 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:25 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:25 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:25 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:25 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:26 INFO - Downloaded 17970719 bytes. 09:17:26 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:26 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:30 INFO - Return code: 0 09:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:30 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:30 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:30 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:30 INFO - Downloaded 10847617 bytes. 09:17:30 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:30 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:30 INFO - Return code: 0 09:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg 09:17:30 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:30 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:31 INFO - Downloaded 108918781 bytes. 09:17:31 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:31 INFO - mkdir: /builds/slave/test/properties 09:17:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:31 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:31 INFO - Contents: 09:17:31 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg 09:17:31 INFO - Running post-action listener: _resource_record_post_action 09:17:31 INFO - Running post-action listener: set_extra_try_arguments 09:17:31 INFO - ##### 09:17:31 INFO - ##### Running populate-webroot step. 09:17:31 INFO - ##### 09:17:31 INFO - Running pre-action listener: _resource_record_pre_action 09:17:31 INFO - Running main action method: populate_webroot 09:17:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:31 INFO - '--tppagecycles', 09:17:31 INFO - '7'], 09:17:31 INFO - 'tests': ['tsvgm']}}, 09:17:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['damp', 'tps']}, 09:17:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'tests': ['damp', 'tps']}, 09:17:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['dromaeo_dom']}, 09:17:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['tsvgx', 09:17:31 INFO - 'tsvgr_opacity', 09:17:31 INFO - 'tart', 09:17:31 INFO - 'tscrollx', 09:17:31 INFO - 'cart']}, 09:17:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:31 INFO - 'tsvgr_opacity', 09:17:31 INFO - 'tart', 09:17:31 INFO - 'tscrollx', 09:17:31 INFO - 'cart']}, 09:17:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['tp5o']}, 09:17:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'tests': ['tp5o']}, 09:17:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--disable-e10s', 09:17:31 INFO - '--xperf_path', 09:17:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:31 INFO - 'tests': ['tp5n']}, 09:17:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--xperf_path', 09:17:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:31 INFO - 'tests': ['tp5n']}}, 09:17:31 INFO - 'talos.zip': {'path': '', 09:17:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:31 INFO - Running post-action listener: _resource_record_post_action 09:17:31 INFO - ##### 09:17:31 INFO - ##### Running create-virtualenv step. 09:17:31 INFO - ##### 09:17:31 INFO - Running pre-action listener: _resource_record_pre_action 09:17:31 INFO - Running main action method: create_virtualenv 09:17:31 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:31 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:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:31 INFO - Using real prefix '/tools/python27' 09:17:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:32 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:35 INFO - Installing pip.................done. 09:17:35 INFO - Return code: 0 09:17:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:35 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:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:35 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:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:35 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 0x10dd32a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd42420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7aa4d8cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, '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.qF7270s3e2/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.QRZsvTneCo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:35 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:35 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:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/Render', 09:17:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:35 INFO - 'HOME': '/Users/cltbld', 09:17:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:35 INFO - 'LOGNAME': 'cltbld', 09:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:35 INFO - 'MOZ_NO_REMOTE': '1', 09:17:35 INFO - 'NO_EM_RESTART': '1', 09:17:35 INFO - 'PAGER': '/bin/cat', 09:17:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:35 INFO - 'PWD': '/builds/slave/test', 09:17:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:35 INFO - 'SHELL': '/bin/bash', 09:17:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QRZsvTneCo/Listeners', 09:17:35 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:17:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:35 INFO - 'USER': 'cltbld', 09:17:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:35 INFO - 'XPC_FLAGS': '0x0', 09:17:35 INFO - 'XPC_SERVICE_NAME': '0', 09:17:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:35 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:35 INFO - Cleaning up... 09:17:35 INFO - Return code: 0 09:17:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:17:35 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:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:35 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:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:35 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 0x10dd32a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd42420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7aa4d8cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, '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.qF7270s3e2/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.QRZsvTneCo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:35 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:35 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:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/Render', 09:17:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:35 INFO - 'HOME': '/Users/cltbld', 09:17:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:35 INFO - 'LOGNAME': 'cltbld', 09:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:35 INFO - 'MOZ_NO_REMOTE': '1', 09:17:35 INFO - 'NO_EM_RESTART': '1', 09:17:35 INFO - 'PAGER': '/bin/cat', 09:17:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:35 INFO - 'PWD': '/builds/slave/test', 09:17:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:35 INFO - 'SHELL': '/bin/bash', 09:17:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QRZsvTneCo/Listeners', 09:17:35 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:17:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:35 INFO - 'USER': 'cltbld', 09:17:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:35 INFO - 'XPC_FLAGS': '0x0', 09:17:35 INFO - 'XPC_SERVICE_NAME': '0', 09:17:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:35 INFO - Downloading/unpacking psutil>=0.7.1 09:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:38 INFO - Installing collected packages: psutil 09:17:38 INFO - Running setup.py install for psutil 09:17:38 INFO - building 'psutil._psutil_osx' extension 09:17:38 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:39 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:39 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:39 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:39 INFO - building 'psutil._psutil_posix' extension 09:17:39 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:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:17:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:17:39 INFO - ^ 09:17:39 INFO - 1 warning generated. 09:17:39 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:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:39 INFO - Successfully installed psutil 09:17:39 INFO - Cleaning up... 09:17:39 INFO - Return code: 0 09:17:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dd32a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd42420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7aa4d8cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, '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.qF7270s3e2/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.QRZsvTneCo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:17:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/Render', 09:17:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:39 INFO - 'HOME': '/Users/cltbld', 09:17:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:39 INFO - 'LOGNAME': 'cltbld', 09:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:39 INFO - 'MOZ_NO_REMOTE': '1', 09:17:39 INFO - 'NO_EM_RESTART': '1', 09:17:39 INFO - 'PAGER': '/bin/cat', 09:17:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:39 INFO - 'PWD': '/builds/slave/test', 09:17:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:39 INFO - 'SHELL': '/bin/bash', 09:17:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QRZsvTneCo/Listeners', 09:17:39 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:17:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:39 INFO - 'USER': 'cltbld', 09:17:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:39 INFO - 'XPC_FLAGS': '0x0', 09:17:39 INFO - 'XPC_SERVICE_NAME': '0', 09:17:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:17:42 INFO - Installing collected packages: mozsystemmonitor 09:17:42 INFO - Running setup.py install for mozsystemmonitor 09:17:42 INFO - Successfully installed mozsystemmonitor 09:17:42 INFO - Cleaning up... 09:17:42 INFO - Return code: 0 09:17:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:17:42 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:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:42 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:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:42 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 0x10dd32a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd42420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7aa4d8cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, '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.qF7270s3e2/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.QRZsvTneCo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/Render', 09:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:42 INFO - 'HOME': '/Users/cltbld', 09:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:42 INFO - 'LOGNAME': 'cltbld', 09:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:42 INFO - 'MOZ_NO_REMOTE': '1', 09:17:42 INFO - 'NO_EM_RESTART': '1', 09:17:42 INFO - 'PAGER': '/bin/cat', 09:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:42 INFO - 'PWD': '/builds/slave/test', 09:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:42 INFO - 'SHELL': '/bin/bash', 09:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QRZsvTneCo/Listeners', 09:17:42 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:42 INFO - 'USER': 'cltbld', 09:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:42 INFO - 'XPC_FLAGS': '0x0', 09:17:42 INFO - 'XPC_SERVICE_NAME': '0', 09:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:43 INFO - Downloading/unpacking blobuploader==1.2.4 09:17:43 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:43 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:43 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:43 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:43 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:43 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:45 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:45 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:45 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:45 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:45 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:45 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:45 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:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:17:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:17:46 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:46 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:46 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:46 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:46 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:46 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:46 INFO - Downloading docopt-0.6.1.tar.gz 09:17:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:46 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:46 INFO - Running setup.py install for blobuploader 09:17:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:46 INFO - Running setup.py install for requests 09:17:47 INFO - Running setup.py install for docopt 09:17:47 INFO - Successfully installed blobuploader requests docopt 09:17:47 INFO - Cleaning up... 09:17:47 INFO - Return code: 0 09:17:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:47 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 0x10dd32a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd42420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7aa4d8cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, '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.qF7270s3e2/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.QRZsvTneCo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:47 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:17:47 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:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/Render', 09:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:47 INFO - 'HOME': '/Users/cltbld', 09:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:47 INFO - 'LOGNAME': 'cltbld', 09:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:47 INFO - 'MOZ_NO_REMOTE': '1', 09:17:47 INFO - 'NO_EM_RESTART': '1', 09:17:47 INFO - 'PAGER': '/bin/cat', 09:17:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:47 INFO - 'PWD': '/builds/slave/test', 09:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:47 INFO - 'SHELL': '/bin/bash', 09:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QRZsvTneCo/Listeners', 09:17:47 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:47 INFO - 'USER': 'cltbld', 09:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:47 INFO - 'XPC_FLAGS': '0x0', 09:17:47 INFO - 'XPC_SERVICE_NAME': '0', 09:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:47 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-xp8oS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:47 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-xckpAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-NceDTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-DGufSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-bUqvng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-EJclVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-3JPo1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-C8XDwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-XuXUJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-CutAmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-dXnb6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:48 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-VClm7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:49 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-uCYdxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:49 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-PLNIJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:49 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-7ewAhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:49 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-duM9go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:49 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-bTPTsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:49 INFO - Running setup.py install for manifestparser 09:17:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:49 INFO - Running setup.py install for mozcrash 09:17:49 INFO - Running setup.py install for mozdebug 09:17:50 INFO - Running setup.py install for mozdevice 09:17:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:50 INFO - Running setup.py install for mozfile 09:17:50 INFO - Running setup.py install for mozhttpd 09:17:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:50 INFO - Running setup.py install for mozinfo 09:17:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:50 INFO - Running setup.py install for mozInstall 09:17:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:50 INFO - Running setup.py install for mozleak 09:17:50 INFO - Running setup.py install for mozlog 09:17:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:51 INFO - Running setup.py install for moznetwork 09:17:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:51 INFO - Running setup.py install for mozprocess 09:17:51 INFO - Running setup.py install for mozprofile 09:17:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:51 INFO - Running setup.py install for mozrunner 09:17:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:51 INFO - Running setup.py install for mozscreenshot 09:17:51 INFO - Running setup.py install for moztest 09:17:52 INFO - Running setup.py install for mozversion 09:17:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:52 INFO - Cleaning up... 09:17:52 INFO - Return code: 0 09:17:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:52 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 0x10dd32a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd42420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7aa4d8cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, '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.qF7270s3e2/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.QRZsvTneCo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:52 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:17:52 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:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/Render', 09:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:52 INFO - 'HOME': '/Users/cltbld', 09:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:52 INFO - 'LOGNAME': 'cltbld', 09:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:52 INFO - 'MOZ_NO_REMOTE': '1', 09:17:52 INFO - 'NO_EM_RESTART': '1', 09:17:52 INFO - 'PAGER': '/bin/cat', 09:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:52 INFO - 'PWD': '/builds/slave/test', 09:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:52 INFO - 'SHELL': '/bin/bash', 09:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QRZsvTneCo/Listeners', 09:17:52 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:52 INFO - 'USER': 'cltbld', 09:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:52 INFO - 'XPC_FLAGS': '0x0', 09:17:52 INFO - 'XPC_SERVICE_NAME': '0', 09:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Mp1y7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-HzR5Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-k2sUGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-TkDsiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:52 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:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:52 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-qHRzAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-GA92a6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-JUHVcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-soToIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-gRWPvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Ks5WLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-TzeGN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-AGYfKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-EgyCFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:53 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:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:53 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-mj_Ixx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:54 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:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:54 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-fRTHAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:54 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:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:54 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-NvuxbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:54 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:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:54 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Rhdz_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:54 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:17:54 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:17:54 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:17:54 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:17:54 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:17:54 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:17:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:56 INFO - Downloading blessings-1.6.tar.gz 09:17:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:56 INFO - Installing collected packages: blessings 09:17:56 INFO - Running setup.py install for blessings 09:17:57 INFO - Successfully installed blessings 09:17:57 INFO - Cleaning up... 09:17:57 INFO - Return code: 0 09:17:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:57 INFO - Reading from file tmpfile_stdout 09:17:57 INFO - Current package versions: 09:17:57 INFO - blessings == 1.6 09:17:57 INFO - blobuploader == 1.2.4 09:17:57 INFO - docopt == 0.6.1 09:17:57 INFO - manifestparser == 1.1 09:17:57 INFO - mozInstall == 1.12 09:17:57 INFO - mozcrash == 0.17 09:17:57 INFO - mozdebug == 0.1 09:17:57 INFO - mozdevice == 0.48 09:17:57 INFO - mozfile == 1.2 09:17:57 INFO - mozhttpd == 0.7 09:17:57 INFO - mozinfo == 0.9 09:17:57 INFO - mozleak == 0.1 09:17:57 INFO - mozlog == 3.1 09:17:57 INFO - moznetwork == 0.27 09:17:57 INFO - mozprocess == 0.22 09:17:57 INFO - mozprofile == 0.28 09:17:57 INFO - mozrunner == 6.11 09:17:57 INFO - mozscreenshot == 0.1 09:17:57 INFO - mozsystemmonitor == 0.0 09:17:57 INFO - moztest == 0.7 09:17:57 INFO - mozversion == 1.4 09:17:57 INFO - psutil == 3.1.1 09:17:57 INFO - requests == 1.2.3 09:17:57 INFO - wsgiref == 0.1.2 09:17:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17: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:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:57 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 0x10dd32a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd42420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7aa4d8cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, '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.qF7270s3e2/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.QRZsvTneCo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:57 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:17:57 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:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/Render', 09:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:57 INFO - 'HOME': '/Users/cltbld', 09:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:57 INFO - 'LOGNAME': 'cltbld', 09:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:57 INFO - 'MOZ_NO_REMOTE': '1', 09:17:57 INFO - 'NO_EM_RESTART': '1', 09:17:57 INFO - 'PAGER': '/bin/cat', 09:17:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:57 INFO - 'PWD': '/builds/slave/test', 09:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:57 INFO - 'SHELL': '/bin/bash', 09:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QRZsvTneCo/Listeners', 09:17:57 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:57 INFO - 'USER': 'cltbld', 09:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:57 INFO - 'XPC_FLAGS': '0x0', 09:17:57 INFO - 'XPC_SERVICE_NAME': '0', 09:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:57 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:17:57 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:17:57 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:17:57 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:17:57 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:17:57 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:17:57 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:17:57 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:17:57 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:17:57 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:17:57 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:17:57 INFO - Cleaning up... 09:17:57 INFO - Return code: 0 09:17:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17: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:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:57 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 0x10dd32a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd42420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7aa4d8cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, '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.qF7270s3e2/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.QRZsvTneCo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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:57 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:17:57 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:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/Render', 09:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:57 INFO - 'HOME': '/Users/cltbld', 09:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:57 INFO - 'LOGNAME': 'cltbld', 09:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:57 INFO - 'MOZ_NO_REMOTE': '1', 09:17:57 INFO - 'NO_EM_RESTART': '1', 09:17:57 INFO - 'PAGER': '/bin/cat', 09:17:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:57 INFO - 'PWD': '/builds/slave/test', 09:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:57 INFO - 'SHELL': '/bin/bash', 09:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QRZsvTneCo/Listeners', 09:17:57 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:57 INFO - 'USER': 'cltbld', 09:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:57 INFO - 'XPC_FLAGS': '0x0', 09:17:57 INFO - 'XPC_SERVICE_NAME': '0', 09:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:57 INFO - Downloading/unpacking jsonschema 09:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:00 INFO - Downloading/unpacking functools32 (from jsonschema) 09:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:00 INFO - Downloading functools32-3.2.3-2.tar.gz 09:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:00 INFO - warning: no files found matching '*.txt' 09:18:00 INFO - no previously-included directories found matching 'build' 09:18:00 INFO - no previously-included directories found matching 'dist' 09:18:00 INFO - no previously-included directories found matching '.git*' 09:18:00 INFO - Installing collected packages: jsonschema, functools32 09:18:00 INFO - Running setup.py install for functools32 09:18:00 INFO - warning: no files found matching '*.txt' 09:18:00 INFO - no previously-included directories found matching 'build' 09:18:00 INFO - no previously-included directories found matching 'dist' 09:18:00 INFO - no previously-included directories found matching '.git*' 09:18:00 INFO - Successfully installed jsonschema functools32 09:18:00 INFO - Cleaning up... 09:18:01 INFO - Return code: 0 09:18:01 INFO - Installing flake8 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dd32a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd42420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7aa4d8cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, '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.qF7270s3e2/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.QRZsvTneCo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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', 'flake8'] 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 flake8 09:18:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/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.QRZsvTneCo/Listeners', 09:18:01 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 flake8 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 flake8-2.5.0-py2.py3-none-any.whl 09:18:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 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 - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:18:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 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/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 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 - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:18:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:18:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:18:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:18:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:18:04 INFO - no previously-included directories found matching 'docs/_build' 09:18:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:18:05 INFO - Running setup.py install for pep8 09:18:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:18:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:18:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:18:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:18:05 INFO - no previously-included directories found matching 'docs/_build' 09:18:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:18:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:18:05 INFO - Cleaning up... 09:18:05 INFO - Return code: 0 09:18:05 INFO - Running post-action listener: _resource_record_post_action 09:18:05 INFO - Running post-action listener: _start_resource_monitoring 09:18:05 INFO - Starting resource monitoring. 09:18:05 INFO - ##### 09:18:05 INFO - ##### Running install step. 09:18:05 INFO - ##### 09:18:05 INFO - Running pre-action listener: _resource_record_pre_action 09:18:05 INFO - Running main action method: install 09:18:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:05 INFO - Reading from file tmpfile_stdout 09:18:05 INFO - Detecting whether we're running mozinstall >=1.0... 09:18:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:18:05 INFO - Reading from file tmpfile_stdout 09:18:05 INFO - Output received: 09:18:05 INFO - Usage: mozinstall [options] installer 09:18:05 INFO - Options: 09:18:05 INFO - -h, --help show this help message and exit 09:18:05 INFO - -d DEST, --destination=DEST 09:18:05 INFO - Directory to install application into. [default: 09:18:05 INFO - "/builds/slave/test"] 09:18:05 INFO - --app=APP Application being installed. [default: firefox] 09:18:05 INFO - mkdir: /builds/slave/test/build/application 09:18:05 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:05 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:38 INFO - Reading from file tmpfile_stdout 09:18:38 INFO - Output received: 09:18:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:18:38 INFO - Running post-action listener: _resource_record_post_action 09:18:38 INFO - ##### 09:18:38 INFO - ##### Running run-tests step. 09:18:38 INFO - ##### 09:18:38 INFO - Running pre-action listener: _resource_record_pre_action 09:18:38 INFO - Running main action method: run_tests 09:18:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:38 INFO - 2016-04-07 09:18:38.919 screenresolution[1964:10780] starting screenresolution argv=screenresolution get 09:18:38 INFO - 2016-04-07 09:18:38.922 screenresolution[1964:10780] Display 0: 1600x1200x32@60 09:18:38 INFO - 2016-04-07 09:18:38.928 screenresolution[1965:10782] starting screenresolution argv=screenresolution list 09:18:38 INFO - Available Modes on Display 0 09:18:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:39 INFO - Intel Iris: 09:18:39 INFO - Chipset Model: Intel Iris 09:18:39 INFO - Type: GPU 09:18:39 INFO - Bus: Built-In 09:18:39 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:39 INFO - Vendor: Intel (0x8086) 09:18:39 INFO - Device ID: 0x0a2e 09:18:39 INFO - Revision ID: 0x0009 09:18:39 INFO - Displays: 09:18:39 INFO - Display: 09:18:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:39 INFO - Display Serial Number: 1600x1200 60 09:18:39 INFO - Main Display: Yes 09:18:39 INFO - Mirror: Off 09:18:39 INFO - Online: Yes 09:18:39 INFO - Rotation: Supported 09:18:39 INFO - Return code: 0 09:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:39 INFO - Python 2.7.3 09:18:39 INFO - Return code: 0 09:18:39 INFO - grabbing minidump binary from tooltool 09:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:39 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 0x10dbd1e00>, '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 0x10d9fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dbccb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:39 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:39 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:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:39 INFO - Return code: 0 09:18:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:18:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:18:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:18:40 INFO - Return code: 0 09:18:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', '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-0162', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath 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-0162 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:18:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qF7270s3e2/Render', 09:18:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:40 INFO - 'HOME': '/Users/cltbld', 09:18:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:40 INFO - 'LOGNAME': 'cltbld', 09:18:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:40 INFO - 'MOZ_NO_REMOTE': '1', 09:18:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:40 INFO - 'NO_EM_RESTART': '1', 09:18:40 INFO - 'PAGER': '/bin/cat', 09:18:40 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:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:40 INFO - 'PWD': '/builds/slave/test', 09:18:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:40 INFO - 'SHELL': '/bin/bash', 09:18:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QRZsvTneCo/Listeners', 09:18:40 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 09:18:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:40 INFO - 'USER': 'cltbld', 09:18:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:40 INFO - 'XPC_FLAGS': '0x0', 09:18:40 INFO - 'XPC_SERVICE_NAME': '0', 09:18:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', '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-0162', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:18:40 INFO - mozversion application_buildid: 20160406112610 09:18:40 INFO - mozversion application_changeset: 55f0de142be46043246b18108299aebf2bd95a64 09:18:40 INFO - mozversion application_display_name: Nightly 09:18:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:40 INFO - mozversion application_name: Firefox 09:18:40 INFO - mozversion application_remotingname: firefox 09:18:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:18:40 INFO - mozversion application_vendor: Mozilla 09:18:40 INFO - mozversion application_version: 48.0a1 09:18:40 INFO - mozversion platform_buildid: 20160406112610 09:18:40 INFO - mozversion platform_changeset: 55f0de142be46043246b18108299aebf2bd95a64 09:18:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:18:40 INFO - mozversion platform_version: 48.0a1 09:18:40 INFO - using testdate: 1460045920 09:18:40 INFO - actual date: 1460045920 09:18:40 INFO - starting webserver on 'localhost:49662' 09:18:40 INFO - SUITE-START | Running 2 tests 09:18:40 INFO - TEST-START | dromaeo_css 09:18:40 INFO - operating with platform_type : mac_ 09:18:40 INFO - Initialising browser for dromaeo_css test... 09:18:40 INFO - TEST-INFO | started process 1983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpFulbqz/profile http://localhost:49662/getInfo.html) 09:18:42 INFO - PROCESS | 1983 | __metrics Screen width/height:1600/1200 09:18:42 INFO - PROCESS | 1983 | colorDepth:24 09:18:42 INFO - PROCESS | 1983 | Browser inner width/height: 1024/689 09:18:42 INFO - PROCESS | 1983 | __metrics 09:18:42 INFO - PROCESS | 1983 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:18:42 ERROR - PROCESS | 1983 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:18:43 INFO - TEST-INFO | 1983: exit 0 09:18:43 INFO - Browser initialized. 09:18:43 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:43 INFO - TEST-INFO | started process 1985 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpFulbqz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:55 INFO - PROCESS | 1985 | RSS: Main: 236924928 09:18:55 INFO - PROCESS | 1985 | 09:19:46 INFO - PROCESS | 1985 | 1460045986711 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:21:57 INFO - PROCESS | 1985 | Cycle 1(1): loaded http://localhost:49662/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49662/tests/dromaeo/cssquery-ext.html) 09:21:58 INFO - PROCESS | 1985 | RSS: Main: 351191040 09:21:58 INFO - PROCESS | 1985 | 09:25:00 INFO - PROCESS | 1985 | Cycle 1(1): loaded http://localhost:49662/tests/dromaeo/cssquery-ext.html (next: http://localhost:49662/tests/dromaeo/cssquery-jquery.html) 09:25:01 INFO - PROCESS | 1985 | RSS: Main: 363024384 09:25:01 INFO - PROCESS | 1985 | 09:28:03 INFO - PROCESS | 1985 | Cycle 1(1): loaded http://localhost:49662/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49662/tests/dromaeo/cssquery-mootools.html) 09:28:03 INFO - PROCESS | 1985 | RSS: Main: 369278976 09:28:03 INFO - PROCESS | 1985 | 09:31:05 INFO - PROCESS | 1985 | Cycle 1(1): loaded http://localhost:49662/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49662/tests/dromaeo/cssquery-prototype.html) 09:31:06 INFO - PROCESS | 1985 | RSS: Main: 327065600 09:31:06 INFO - PROCESS | 1985 | 09:34:09 INFO - PROCESS | 1985 | Cycle 1(1): loaded http://localhost:49662/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49662/tests/dromaeo/cssquery-yui.html) 09:34:09 INFO - PROCESS | 1985 | RSS: Main: 329588736 09:34:09 INFO - PROCESS | 1985 | 09:37:12 INFO - PROCESS | 1985 | Cycle 1(1): loaded http://localhost:49662/tests/dromaeo/cssquery-yui.html (next: http://localhost:49662/tests/dromaeo/cssquery-dojo.html) 09:37:12 INFO - PROCESS | 1985 | RSS: Main: 375427072 09:37:12 INFO - PROCESS | 1985 | 09:37:12 INFO - PROCESS | 1985 | __start_tp_report 09:37:12 INFO - PROCESS | 1985 | _x_x_mozilla_page_load 09:37:12 INFO - PROCESS | 1985 | _x_x_mozilla_page_load_details 09:37:12 INFO - PROCESS | 1985 | |i|pagename|runs| 09:37:12 INFO - PROCESS | 1985 | |0;dojo.html;3398.337324221777;3398.8274045454327;3426.5888093428794;3444.2422667013257;3475.131217195701;5561.944380556193;5627;5645.096784514473;5650.660960342382;5689.175069614908;5589.832305030845;5637.295338082739;5650.406707295726;5764.337101233359;5784.884302313952;5007.449180590151;5040.42035165956;5046.21783623539;5075.238714192882;5174.715390653512;5180.507851754096;5183.403908550527;5212.374515058198;5257.7108259045735;5275.182346736262;4987.675801072936;5027.622928280393;5112.079825631385;5114.539691427753;5114.846554603368;5107.438181799999;5111;5116.974415127937;5128.25640282157;5131.87170320745;5047.520485553867;5080.974595127001;5103.464136265676;5124.436312005676;5124.846254612367;5440.945590544084;5523.2543606613;5566.359868615121;5576.525995290405;5613.887722245532;6154.84612884677;6164.198654174928;6169.475594574466;6173.47525460332;6180.84547886302;6147.72335244915;6186.10301506279;6195.473384762301;6198.876022479571;6207.068939659042;5635.802746903839;5729.341125770548;5737.741801618937;5761.0494268445655;5810.070388737781;6221.097940798559;6232.688365581703;6244.781432649836;6249.937500625027;6256.562040657202;6032.125341825411;6057.515398768176;6111.388861113853;6122.510199184055;6147.508199344131;6119.4798442132915;6139.785107521216;6182.783602573888;6185.690715464209;6200.472959798378;5037.345145131109;6175.3824617538785;6178.938210617836;6192.907106393318;6193.102000209944;6188.566800324024;6198.101275315054;6198.380161983766;6211.503079753609;6212.06818977157;4799.232122860326;4801.591864691471;4846.539578740014;4873.26900964851;4917.434495033117;3574.4459608760685;4414.315781053878;4428.756418397019;4431.047324825178;4449.688521803443;5485;5525.392206857243;5534.806281780118;5536.52939500147;5544.085225937756;6218.533609979269;6237.09562113491;6249.0626406039455;6260.436560709558;6269.028300613412;5421.403645598981;5477.123660214346;5512.283403157564;5547.972260138674;5571.136473846479;5303.787848486095;5326.174442961347;5326.840194794162;5330.786768529216;5339.5728341732565;6206.627602343874;6254.093156492283;6254.15568898202;6267.592606480564;6279.183706118175;6111.694415279129;6144.877102457836;6212.875742485216;6247.375262473716;6266.342034086538;5240.423553409046;6219.346968568235;6231.750729970931;6242.375762423721;6270.372962703876;6009.579329446897;6155.230596175478;6166.506679465542;6189.288231853395;6197.907031394442;5992.250968628921;6098.878022439438;6112.938870611415;6120.843333897588;6137.232845894263;6065.878682426415;6072.028475443908;6082.087686846831;6086.60437071589;6087.969560152171;5868.882622347614;5908.675022873783;5981.611195272293;6069.51443884497;6071.241094863142;4829.830955916526;5906.675132867734;5918.378570250234;5920.230370051866;5957.851053723692;6018.8194354169445;6061.636301821904;6073.635581865102;6081.422264884829;6103.084537319438;5766.798162064308;5843.357230704704;5877.4122587742595;5895.469407753323;5930.021546444788 09:37:12 INFO - PROCESS | 1985 | |1;ext.html;490.2694984473136;548.6324162810917;565.5503874419833;630.1555915073801;633.6894921488471;38847.41728874069;39255;39317.8034109829;39350.409743853794;39354.2129157417;42750;43125.49060782873;43190.568094319045;43207.13585728283;43218.783906080505;9516.714498565038;9717.416954982687;9740;9743.902560974406;9754.902450975505;9590.66432674853;9740.025997400238;9750.219982401391;9846.015398460167;9870.703878883609;9860.161886239679;9905.356151850143;9907.157891579225;9918.25613079022;9918.3057185997;9805.36265142767;9872.55573499187;9881.851772223423;9889.554970026365;9891.406515609051;9706.805863882682;9842.507874606312;9859.408435493893;9864.95067524665;9889.950550247275;41149.58850411487;41503.58496415058;41550.79224603888;41634.583654163376;41640.37539436911;3009.623797025372;3180.9204769880707;3217.115293294339;3361.83190840457;3878.7866667333287;24677.753222467723;27622.033228836895;29685;29781.87290334527;30578.235544111354;2686.7313268672974;2738.112325960083;2780.902668406596;2873.310405502685;2901.7823663225345;486182;490314.5484272556;490845;491139;491532;62777.68611156915;67967.66016169888;68134.9779753304;68241.65879170672;68260.97608535876;71230.28769712236;71385.28614713787;71573.64213179005;71607;71703.2829671707;17484.300627974993;17701.55746106332;17713.822861771216;17717.379891703986;17741.93548387101;11286.040686541572;13166.736665266833;13254.27101509407;13257.204567725967;13267.336633168303;19325;20858.47853803637;20864.478388040116;20972.79027209739;21045;6664.633445160563;7344.48588598793;7350.897365395126;7431.777046688608;7695.538267703956;830.6345208108413;945.3240932733129;957.7189960874878;1072.7103682005816;1078.8166011778017;28444.71555284462;28528.857355713088;29036.12891613255;29145.56281655777;29281.56077658837;1054.4516851237468;1066.4561073852235;1085.6688709943385;1160.2400427719856;1161.8157863217266;14530.346134423977;14838.40646374133;14962.850371496363;15213.543593692208;15321.46374876874;541337;541469.5853041419;546634;549577;549578;59555;60051.69974150101;60059.798804023674;60138.699306504066;60227.39772602218;1315.8223639808696;1329.4017692038622;1430.3277459593974;1477.062065588359;1588.4281658602895;29180.416391672472;29383.559246611745;29473.85263073671;30101.397972040875;30261.546076808427;48753;48984;49311;49327.7533612344;49357.01285974189;375032;376779;377198;377615;377901;28726.85636571804;32025.679743203204;34330.970070897914;34334.87981442243;34415.65584344124;40946.79526602348;42383;42516;42831.785841070596;42832.78583607062;35032.82483587566;35496.290074198885;35696.28607427889;36257.63742362543;36382.454263185544;34858.9542313731;34892;35656.465153023244;35713.64286357103;35729.64270357367;35907.28185436329;36004.09989750172;36349.72775952829;37007.25985480329;37081.44377834281;31350.74597016185;31740.732040126346;32114.678853211168;32227.35545289034;32246.548905299787;21286.574268514232;21844.344669659935;24109.408732156986;24250.87874560616;24501.38746531351 09:37:12 INFO - PROCESS | 1985 | |2;jquery.html;364.3332701156883;366.29853829915714;367.5185506985847;375.7520036775729;379.15259395251627;19512.31706890259;20383.980800959966;20708;21107.788922110794;21573.892130539363;20114.09486573104;20769.792302077014;20943.37169884906;21085.472863178427;21139.154433822667;5823.010088285002;6063.333033366326;6075;6082.543809214305;6113.449789518942;5661.2923384576925;6024.608400453978;6033.637981721089;6036.486898613623;6040.879182416349;5945.732442040118;5973.163757073991;5977.611455255416;5979.820605381846;5988.341282458948;5640.689762063085;6017.067354560028;6017.217761690974;6019.428154325332;6032.396760323976;5157.69053856766;5183.688978661255;5187.118189907725;5193.402758682724;5196.32447781786;24087.397815054763;24343.782810859386;24700.011999519997;24786;24810.5037899241;2374.5607062693457;2415.589349810519;2483.8137139714413;2640.6435131257226;2808.873600687974;13263.50617382378;13307.268100254481;14380.424783008668;14517.983741138125;14705.926470367687;1680.1851102215417;1717.441831404786;1733.0468242466593;1752.7546143539914;1794.605186858902;79652;93711.53144234236;94078.5296073522;98064.0193598062;98253.52619710639;10104.090631843022;10441.373517588969;10465.37207767521;10529.631462898762;10624.893751062546;12785.296808675612;12884.48462061507;12920.289384083966;12931.5473958411;12940.482380704667;8320.542370169698;8480.10958849312;8480.957595211985;8589.699360522352;8602.913970860336;8776.385653004356;8780.692675756445;8790.86813697795;8943.17068782863;8947.597358118901;5039.974800125976;5881.617694849821;5976.644375686231;5986.281646202397;5996.700164991733;1692.1031853117868;1786.1426515272742;1808.9185986630632;1814.392178620177;1834.0462959261376;835.9749207523784;858.914108589136;931.6739141300626;1000.1198944928417;1028.840529717895;19764.325941482843;19834.60330793376;20373.694394584094;20489.692654610193;20854.58290834175;934.9205317547949;977.5405559387077;978.8629591857145;993.3146129170849;1067.4716015572342;11279.887201127884;11423.543058277575;11468.082553395709;11487.368194749202;11508.870858891132;557358;557718;568846;575666;575847;95024;99274;99455.50272248738;99572.50641240388;99853.50073249733;1029.7683021320142;1048.486241741541;1059.782744537368;1064.7923654887222;1065.6430095917954;17820.55448613795;18001.459956201335;18584.163712632693;18672.86496213552;19812;50690;52377.8081171652;55207.44792552023;56350.718246408505;56366.43633563612;70462.23844403726;71171.64414177896;71436.64281678558;71807;74244.2575574259;7792.142864285038;8032;8168.060673022678;11081.667549973514;11340.505088880433;10225.693229203136;12080.818787718015;12414.875851241733;12771.361431928552;13505.324733763076;9997.60009599637;10339.586416543254;11074.501647426046;11472.254303470581;11758.58844940423;9859.161971232526;10052;10452.947735261276;10496.580136794442;11002.449877505933;10312.639057632947;10833.404162770808;11679.474423651127;11873.910286891714;11946.163768536118;11609.955104040677;11640.301581905112;11742.647720568397;11808.645740627795;11814.232074915102;11367.943160284145;11423.543058277908;11426.314421134759;11784.70538236551;11815.70460738454 09:37:12 INFO - PROCESS | 1985 | |3;mootools.html;1313.7306852095319;1423.4377420918743;1582.905025698458;1760.4982579964712;1954.9120289586976;7928.246816552432;8593.054763975957;8770.298376129911;8783.253423459013;8795.736127916156;8471.941007374078;8745.475271483716;8755.299576033918;8755.430896991691;8789.077146899579;2430.2587710748217;2463.4210960424284;2464.051340234005;2466.075221791828;2469.802415806731;2384.4158181245534;2410.252821625293;2499.3626625210636;2502.799776017914;2505.386180385799;2458.889349979246;2480;2495.301315631626;2497.5629264878576;2500.312414086132;2444.9266522004277;2474.814388920829;2480.0575781203206;2482.739312372211;2493.675822143128;2034.257496013959;2235.58641651295;2287.1651847075696;2396.8202384821207;2448.816338774599;8802.427842190302;8874.112588741138;8878.245349145265;8879.556022198864;8907.510086945216;5322.866928326784;5491.972540137313;5527.032769265403;5531.225628412026;5531.944680553183;3509.9649003509894;3564.180238545149;3639.2357604903004;3643.508126402928;3645.2345007548447;1014.0822555587132;1038.657243109772;1045.3936716704293;1047.8218702820539;1061.4162210784039;11834.881651183465;11941.641750747405;11964.70088247792;11982.921537061618;11988.760224795455;4773.212419950739;4838.346823178826;4912.477132089064;4925.482824303468;4928.630352723489;10137.695869123938;10142.797144057078;10152.340097893613;10157.136643385247;10165.898341016644;5481.177823326533;5483.479069488392;5489.752961116759;5491.890162196733;5497.038018346773;4371.169477799272;4565.954340456619;4829.372181616367;4944.085344211332;4989;3830.75100118497;3900.9609903900596;3904.882853514399;3929.332013557702;3955.7428767130045;1300.7138429545485;1307.7449897269942;1311.0822424303026;1320.5246111399888;1326.290434617475;961.6874515782259;1003.0320740485466;1102.2835157147917;1246.1588427811191;1391.325207274471;7885;7936.920630793734;8062.879056814152;8074.273315401525;8130.268275855202;1194.7072967122936;1202.4709127983658;1203.9097067719956;1210.2315029955864;1215.671768622467;6198.597091189058;6234.5635805494085;6320.051992201206;6322.083297921776;6324.209473815773;10376.844347334796;10825.729356766144;10895;10995.725106872233;10998.450077496092;10439.060484556427;10588.599814094867;10674.519646615921;10752.623658172082;10809.405482698527;8243.216894395024;8413.200745929249;8419;8467.364947628952;8491.490510569261;7709.383249340151;7881.605919703878;7947.364210863001;7948;7960.800979975315;9388.906110939077;9632.133108020313;9685.757856053655;9740.707778766648;9746.122848943629;9842.21262299;9858.014198580084;9936.652217172365;10018.998100189923;10035.648752293635;7368.59472729005;7513.586752728653;7612.391008719178;7636.427267954815;7642.7325043623205;8224;8283.420160588701;8295.792605184677;8305.95847020761;8307.626156823078;6244.594101383395;7418.443616728659;7533.096028476618;7650.617469126632;7684.3084122429245;6954.408875245661;7096.290370962863;7131.393831524379;7147.3210045045635;7214.74748383804;7328.560286382834;7412.444066695128;7416.851662966818;7417.109946806418;7430.554166749796;6820.113385259884;6893.896591551231;6928.7228510859;6936.618485983319;6944.618046007518;6883.105196324646;7066.57600543969;7069.82325441868;7096.0420343253;7102.751403700846 09:37:12 INFO - PROCESS | 1985 | |4;prototype.html;317.49993759828277;322.7079493058782;323.1082213091867;330.0478120622005;332.9134425049487;6211.689415529228;6294.43350098491;6353.332900045487;6371.936280637192;6424.3575642435735;5544.306961629796;5857.213549867289;6240.001599744041;6277.811665650038;6302.495800335973;4395.340698895159;4475.440569928759;4525.32120181972;4538.364628951949;4639.65202609804;4571.337156112361;4623.79192936317;4631.39791827062;4649.88375290617;4659.906801863961;4595.540445955411;4596;4598.057398233371;4610.193216187154;4617.145828021827;4313.503947046099;4420.138073075752;4568.588827005586;4570.748608826513;4585.403897493321;3995.62041606047;3997.700172487075;3997.8200980955635;4001.5398229203433;4009.9975006248437;5940.227770389822;5941.970290148522;5947.732352044125;5952.702364881782;5966.164736936832;5357.9819834231375;5361.08861493547;5369.650972686802;5374.140137577969;5383.730813459311;6553.9344606553805;6554.868902621968;6558.1474408327085;6558.475321974231;6568;4161.9375709364385;4337.132573485284;4458.687891847572;4459.241928872067;4467.709598876095;6915.031895534629;6961.477889158334;7011.789646310568;7017.3684368406575;7052.823679408056;4447.510773814878;4456.7548784817145;4462.598366147063;4463.196624607602;4466.6873318867365;4534.59188673021;4541.43232095988;4546.999660074778;4547.886302842456;4547.976705241294;4384.254676704968;5328.094223981932;5334.839390047037;5339.359276886798;5349.866253343697;6434.871302573923;6671.499637527205;6735.8989615155815;6757.560758550679;6773.6613169341335;1534.2252162657787;1554.860062594372;1564.6010267381744;1564.984350156484;1566.8746500279951;909.2634965677822;932.8060083093651;993.1756641987278;1009.4448053570507;1012.5089331674133;5366.973165134227;5909.704514774244;6043.274807023097;6141.938580614226;6155.261368635703;5664.943350566524;7049.295070493013;7076.575405475688;7079.89380159298;7087.1849737279845;6246.906296405557;6253.6873156342;6256.124142620037;6269.68651567429;6270.561060725706;3668.284684486553;3715.5727091384297;3725.0873535983856;3730.813459327023;3732.0669832541867;5826.76692932278;7011.754003087305;7211.639418029077;7312.23221561739;7312.268773122645;7192.964035179791;7250.963745181346;7253.8186545336785;7254.165770936304;7269.89095163573;7309.89035164473;7317.5609463432365;7318.780436586911;7319.41444684424;7330.853382932205;6961.512694111364;6991.510594258353;7023.157221133292;7037.064807178067;7046.506744528039;5547.141707527862;6648.069270302065;6919.757808476679;6936.583804971718;6946.826329341807;7257.709691612276;7292.197858235696;7294.781156565312;7309.634518274172;7317.085364329459;4472.418585583853;4482.8655140345845;4488.820447182076;4489.865304040884;4512.706674066175;6985.476089293222;6985.860282794214;7006.684699188447;7007.229204787572;7012.439004879496;6528.804135875931;6729.899051514133;6782.134912898992;6802.0477133202385;6810.489213309121;5377.919331209957;5388.56891448691;6739.59562426256;6756.628385438651;6778.3221677831825;6624.072629831731;6630.76792312286;6646.634435105922;6653.833654158491;6663;6757.594544327356;6761.560498567577;6766.424853887278;6775;6780.830479238059;6421.261554921244;6638.203415589967;6647.135872336566;6653.800385988428;6657.434118099822 09:37:12 INFO - PROCESS | 1985 | |5;yui.html;641.3266070625842;659.7558903205814;661.583202582373;661.9073329733841;694.8367133723577;14647.414103435864;14827.77758333626;14846.925765371157;14852.331645075985;14854.925725371371;13904.652383690413;13967.720645587107;13983.930080349584;13987.930060349709;15412.691746165097;903.1645727701866;910.6084383715;919.0946917286501;919.5494207838144;920.7513971227764;901.085398320705;911.7811725185974;912.2109375390273;912.7489940266413;914.095045904553;963.9325247232697;964.6093332200435;966.1256562810668;966.917811985982;968.9903100969005;953.1326492891435;958.5255298627154;960.9957594313959;961.9130382667576;962.5572236771094;692.4045321023966;719.2627556754317;719.6905330707793;720.2545365546634;721.3435773446139;15016.849831501655;15061.698766024727;15083.095014299177;15107.169105699291;15108.773368399483;12332.260064396165;12428.813567796491;12447.813282800766;12452.564160254438;12463.18989265704;8810.86783698245;9936;9976.451295178762;10334.483275836254;10347.223958203089;3471.8090505022215;3495.5805303363504;3520.8591656333715;3558.5907620623443;3666.4500324951205;57493.850122997304;67602;72457.63771181164;81986.18013819846;82944.17055829485;9316.627334906663;9350.158485736316;9464.290178236659;9496.050394960586;10014.734871866223;21043.68434473484;21051.894740526506;21058.368248952556;21063.262785802424;21125.577488450454;7240.601766902765;7558.811029724191;7568.772936811904;7619.009528761226;7733.072031356161;4165.687573432005;4167.291560434734;4213.304804707189;4216.072464057902;4320.783960801948;3533.9823300883845;3537.02731748766;3578.6779189873037;3579.2304654499408;3583.4087375582735;606.7451670298485;617.7559863853843;655.4068567946016;662.1392190152782;667.0128210248859;590.2238556298454;605.252513146264;618.5639124417365;623.5261201486902;635.4217860316409;8008.679652813938;8147;8205.097439281673;8305.46014509055;8429.32565394767;495.6901936289819;504.85106893466514;532.4515748778764;591.2845456996996;593.5222146172373;3690.5202323698286;3746.4193050077283;3789.2989796887664;3791.450239715281;3975.3639417693607;82984;83579.5821020891;83738.58130709307;83760.16239837524;87788.56105719431;76959;77424;78077;78335.60832195917;79647.60176199082;60455.09317360243;62245;63492.047619285746;63739.6813015932;64085.50550768917;7675.078990521173;7675.61621918913;7736.132830008339;7801.726939557316;7803.648835802289;47606.23937606212;47742.761286193345;47750.283745743145;47792.56622301337;48471.5152848467;58685.70657146687;58912;62274;62943.6852815733;63785.259326660824;9831.606735730491;10553.208509361983;10790.622328218475;10903.836442453528;11264.605738799102;11688.415579221175;11795.29228246308;11816.527338906348;11863.644090677293;11979.940100299442;11187;11346.716332091433;11486.081113510732;11593.304401735923;11612.12909031809;11118.61084862026;11169.776604468028;11171.329720216812;11269.49287282058;11525.135614828754;9288.72133835986;10992.230543861853;11069.5572177112;11083.501242443948;11189.72025699364;9204.125608067223;9382.812343753223;9408.576614052521;9436.575354108943;9567.469204927178;8987.730368088967;9284.72145835626;9598.66404675833;9660.659116885068;9677.322587419094 09:37:12 INFO - PROCESS | 1985 | __end_tp_report 09:37:12 INFO - PROCESS | 1985 | __start_cc_report 09:37:12 INFO - PROCESS | 1985 | _x_x_mozilla_cycle_collect,1089 09:37:12 INFO - PROCESS | 1985 | __end_cc_report 09:37:12 INFO - PROCESS | 1985 | __startTimestamp1460047032769__endTimestamp 09:37:12 INFO - PROCESS | 1985 | 09:37:12 INFO - PROCESS | 1985 | ------- Summary: start ------- 09:37:12 INFO - PROCESS | 1985 | Number of tests: 6 09:37:12 INFO - PROCESS | 1985 | 09:37:12 INFO - PROCESS | 1985 | [#0] dojo.html Cycles:165 Average:5659.13 Median:5895.47 stddev:647.33 (11.0%) stddev-sans-first:624.55 09:37:12 INFO - PROCESS | 1985 | Values: 3398.3 3398.8 3426.6 3444.2 3475.1 5561.9 5627.0 5645.1 5650.7 5689.2 5589.8 5637.3 5650.4 5764.3 5784.9 5007.4 5040.4 5046.2 5075.2 5174.7 5180.5 5183.4 5212.4 5257.7 5275.2 4987.7 5027.6 5112.1 5114.5 5114.8 5107.4 5111.0 5117.0 5128.3 5131.9 5047.5 5081.0 5103.5 5124.4 5124.8 5440.9 5523.3 5566.4 5576.5 5613.9 6154.8 6164.2 6169.5 6173.5 6180.8 6147.7 6186.1 6195.5 6198.9 6207.1 5635.8 5729.3 5737.7 5761.0 5810.1 6221.1 6232.7 6244.8 6249.9 6256.6 6032.1 6057.5 6111.4 6122.5 6147.5 6119.5 6139.8 6182.8 6185.7 6200.5 5037.3 6175.4 6178.9 6192.9 6193.1 6188.6 6198.1 6198.4 6211.5 6212.1 4799.2 4801.6 4846.5 4873.3 4917.4 3574.4 4414.3 4428.8 4431.0 4449.7 5485.0 5525.4 5534.8 5536.5 5544.1 6218.5 6237.1 6249.1 6260.4 6269.0 5421.4 5477.1 5512.3 5548.0 5571.1 5303.8 5326.2 5326.8 5330.8 5339.6 6206.6 6254.1 6254.2 6267.6 6279.2 6111.7 6144.9 6212.9 6247.4 6266.3 5240.4 6219.3 6231.8 6242.4 6270.4 6009.6 6155.2 6166.5 6189.3 6197.9 5992.3 6098.9 6112.9 6120.8 6137.2 6065.9 6072.0 6082.1 6086.6 6088.0 5868.9 5908.7 5981.6 6069.5 6071.2 4829.8 5906.7 5918.4 5920.2 5957.9 6018.8 6061.6 6073.6 6081.4 6103.1 5766.8 5843.4 5877.4 5895.5 5930.0 09:37:12 INFO - PROCESS | 1985 | 09:37:12 INFO - PROCESS | 1985 | [#1] ext.html Cycles:180 Average:62269.38 Median:190889.89 stddev:126585.54 (66.3%) stddev-sans-first:126855.66 09:37:12 INFO - PROCESS | 1985 | Values: 490.3 548.6 565.6 630.2 633.7 38847.4 39255.0 39317.8 39350.4 39354.2 42750.0 43125.5 43190.6 43207.1 43218.8 9516.7 9717.4 9740.0 9743.9 9754.9 9590.7 9740.0 9750.2 9846.0 9870.7 9860.2 9905.4 9907.2 9918.3 9918.3 9805.4 9872.6 9881.9 9889.6 9891.4 9706.8 9842.5 9859.4 9865.0 9890.0 41149.6 41503.6 41550.8 41634.6 41640.4 3009.6 3180.9 3217.1 3361.8 3878.8 24677.8 27622.0 29685.0 29781.9 30578.2 2686.7 2738.1 2780.9 2873.3 2901.8 486182.0 490314.5 490845.0 491139.0 491532.0 62777.7 67967.7 68135.0 68241.7 68261.0 71230.3 71385.3 71573.6 71607.0 71703.3 17484.3 17701.6 17713.8 17717.4 17741.9 11286.0 13166.7 13254.3 13257.2 13267.3 19325.0 20858.5 20864.5 20972.8 21045.0 6664.6 7344.5 7350.9 7431.8 7695.5 830.6 945.3 957.7 1072.7 1078.8 28444.7 28528.9 29036.1 29145.6 29281.6 1054.5 1066.5 1085.7 1160.2 1161.8 14530.3 14838.4 14962.9 15213.5 15321.5 541337.0 541469.6 546634.0 549577.0 549578.0 59555.0 60051.7 60059.8 60138.7 60227.4 1315.8 1329.4 1430.3 1477.1 1588.4 29180.4 29383.6 29473.9 30101.4 30261.5 48753.0 48984.0 49311.0 49327.8 49357.0 375032.0 376779.0 377198.0 377615.0 377901.0 28726.9 32025.7 34331.0 34334.9 34415.7 40946.8 42383.0 42516.0 42831.8 42832.8 35032.8 35496.3 35696.3 36257.6 36382.5 34859.0 34892.0 35656.5 35713.6 35729.6 35907.3 36004.1 36349.7 37007.3 37081.4 31350.7 31740.7 32114.7 32227.4 32246.5 21286.6 21844.3 24109.4 24250.9 24501.4 09:37:12 INFO - PROCESS | 1985 | 09:37:12 INFO - PROCESS | 1985 | [#2] jquery.html Cycles:180 Average:32699.19 Median:13379.69 stddev:93609.45 (699.6%) stddev-sans-first:93840.56 09:37:12 INFO - PROCESS | 1985 | Values: 364.3 366.3 367.5 375.8 379.2 19512.3 20384.0 20708.0 21107.8 21573.9 20114.1 20769.8 20943.4 21085.5 21139.2 5823.0 6063.3 6075.0 6082.5 6113.4 5661.3 6024.6 6033.6 6036.5 6040.9 5945.7 5973.2 5977.6 5979.8 5988.3 5640.7 6017.1 6017.2 6019.4 6032.4 5157.7 5183.7 5187.1 5193.4 5196.3 24087.4 24343.8 24700.0 24786.0 24810.5 2374.6 2415.6 2483.8 2640.6 2808.9 13263.5 13307.3 14380.4 14518.0 14705.9 1680.2 1717.4 1733.0 1752.8 1794.6 79652.0 93711.5 94078.5 98064.0 98253.5 10104.1 10441.4 10465.4 10529.6 10624.9 12785.3 12884.5 12920.3 12931.5 12940.5 8320.5 8480.1 8481.0 8589.7 8602.9 8776.4 8780.7 8790.9 8943.2 8947.6 5040.0 5881.6 5976.6 5986.3 5996.7 1692.1 1786.1 1808.9 1814.4 1834.0 836.0 858.9 931.7 1000.1 1028.8 19764.3 19834.6 20373.7 20489.7 20854.6 934.9 977.5 978.9 993.3 1067.5 11279.9 11423.5 11468.1 11487.4 11508.9 557358.0 557718.0 568846.0 575666.0 575847.0 95024.0 99274.0 99455.5 99572.5 99853.5 1029.8 1048.5 1059.8 1064.8 1065.6 17820.6 18001.5 18584.2 18672.9 19812.0 50690.0 52377.8 55207.4 56350.7 56366.4 70462.2 71171.6 71436.6 71807.0 74244.3 7792.1 8032.0 8168.1 11081.7 11340.5 10225.7 12080.8 12414.9 12771.4 13505.3 9997.6 10339.6 11074.5 11472.3 11758.6 9859.2 10052.0 10452.9 10496.6 11002.4 10312.6 10833.4 11679.5 11873.9 11946.2 11610.0 11640.3 11742.6 11808.6 11814.2 11367.9 11423.5 11426.3 11784.7 11815.7 09:37:12 INFO - PROCESS | 1985 | 09:37:12 INFO - PROCESS | 1985 | [#3] mootools.html Cycles:180 Average:6056.78 Median:5155.93 stddev:3199.84 (62.1%) stddev-sans-first:3188.95 09:37:12 INFO - PROCESS | 1985 | Values: 1313.7 1423.4 1582.9 1760.5 1954.9 7928.2 8593.1 8770.3 8783.3 8795.7 8471.9 8745.5 8755.3 8755.4 8789.1 2430.3 2463.4 2464.1 2466.1 2469.8 2384.4 2410.3 2499.4 2502.8 2505.4 2458.9 2480.0 2495.3 2497.6 2500.3 2444.9 2474.8 2480.1 2482.7 2493.7 2034.3 2235.6 2287.2 2396.8 2448.8 8802.4 8874.1 8878.2 8879.6 8907.5 5322.9 5492.0 5527.0 5531.2 5531.9 3510.0 3564.2 3639.2 3643.5 3645.2 1014.1 1038.7 1045.4 1047.8 1061.4 11834.9 11941.6 11964.7 11982.9 11988.8 4773.2 4838.3 4912.5 4925.5 4928.6 10137.7 10142.8 10152.3 10157.1 10165.9 5481.2 5483.5 5489.8 5491.9 5497.0 4371.2 4566.0 4829.4 4944.1 4989.0 3830.8 3901.0 3904.9 3929.3 3955.7 1300.7 1307.7 1311.1 1320.5 1326.3 961.7 1003.0 1102.3 1246.2 1391.3 7885.0 7936.9 8062.9 8074.3 8130.3 1194.7 1202.5 1203.9 1210.2 1215.7 6198.6 6234.6 6320.1 6322.1 6324.2 10376.8 10825.7 10895.0 10995.7 10998.5 10439.1 10588.6 10674.5 10752.6 10809.4 8243.2 8413.2 8419.0 8467.4 8491.5 7709.4 7881.6 7947.4 7948.0 7960.8 9388.9 9632.1 9685.8 9740.7 9746.1 9842.2 9858.0 9936.7 10019.0 10035.6 7368.6 7513.6 7612.4 7636.4 7642.7 8224.0 8283.4 8295.8 8306.0 8307.6 6244.6 7418.4 7533.1 7650.6 7684.3 6954.4 7096.3 7131.4 7147.3 7214.7 7328.6 7412.4 7416.9 7417.1 7430.6 6820.1 6893.9 6928.7 6936.6 6944.6 6883.1 7066.6 7069.8 7096.0 7102.8 09:37:12 INFO - PROCESS | 1985 | 09:37:12 INFO - PROCESS | 1985 | [#4] prototype.html Cycles:180 Average:5474.31 Median:6243.45 stddev:1751.82 (28.1%) stddev-sans-first:1713.44 09:37:12 INFO - PROCESS | 1985 | Values: 317.5 322.7 323.1 330.0 332.9 6211.7 6294.4 6353.3 6371.9 6424.4 5544.3 5857.2 6240.0 6277.8 6302.5 4395.3 4475.4 4525.3 4538.4 4639.7 4571.3 4623.8 4631.4 4649.9 4659.9 4595.5 4596.0 4598.1 4610.2 4617.1 4313.5 4420.1 4568.6 4570.7 4585.4 3995.6 3997.7 3997.8 4001.5 4010.0 5940.2 5942.0 5947.7 5952.7 5966.2 5358.0 5361.1 5369.7 5374.1 5383.7 6553.9 6554.9 6558.1 6558.5 6568.0 4161.9 4337.1 4458.7 4459.2 4467.7 6915.0 6961.5 7011.8 7017.4 7052.8 4447.5 4456.8 4462.6 4463.2 4466.7 4534.6 4541.4 4547.0 4547.9 4548.0 4384.3 5328.1 5334.8 5339.4 5349.9 6434.9 6671.5 6735.9 6757.6 6773.7 1534.2 1554.9 1564.6 1565.0 1566.9 909.3 932.8 993.2 1009.4 1012.5 5367.0 5909.7 6043.3 6141.9 6155.3 5664.9 7049.3 7076.6 7079.9 7087.2 6246.9 6253.7 6256.1 6269.7 6270.6 3668.3 3715.6 3725.1 3730.8 3732.1 5826.8 7011.8 7211.6 7312.2 7312.3 7193.0 7251.0 7253.8 7254.2 7269.9 7309.9 7317.6 7318.8 7319.4 7330.9 6961.5 6991.5 7023.2 7037.1 7046.5 5547.1 6648.1 6919.8 6936.6 6946.8 7257.7 7292.2 7294.8 7309.6 7317.1 4472.4 4482.9 4488.8 4489.9 4512.7 6985.5 6985.9 7006.7 7007.2 7012.4 6528.8 6729.9 6782.1 6802.0 6810.5 5377.9 5388.6 6739.6 6756.6 6778.3 6624.1 6630.8 6646.6 6653.8 6663.0 6757.6 6761.6 6766.4 6775.0 6780.8 6421.3 6638.2 6647.1 6653.8 6657.4 09:37:12 INFO - PROCESS | 1985 | 09:37:12 INFO - PROCESS | 1985 | [#5] yui.html Cycles:180 Average:17341.62 Median:62259.50 stddev:23771.96 (38.2%) stddev-sans-first:23805.58 09:37:12 INFO - PROCESS | 1985 | Values: 641.3 659.8 661.6 661.9 694.8 14647.4 14827.8 14846.9 14852.3 14854.9 13904.7 13967.7 13983.9 13987.9 15412.7 903.2 910.6 919.1 919.5 920.8 901.1 911.8 912.2 912.7 914.1 963.9 964.6 966.1 966.9 969.0 953.1 958.5 961.0 961.9 962.6 692.4 719.3 719.7 720.3 721.3 15016.8 15061.7 15083.1 15107.2 15108.8 12332.3 12428.8 12447.8 12452.6 12463.2 8810.9 9936.0 9976.5 10334.5 10347.2 3471.8 3495.6 3520.9 3558.6 3666.5 57493.9 67602.0 72457.6 81986.2 82944.2 9316.6 9350.2 9464.3 9496.1 10014.7 21043.7 21051.9 21058.4 21063.3 21125.6 7240.6 7558.8 7568.8 7619.0 7733.1 4165.7 4167.3 4213.3 4216.1 4320.8 3534.0 3537.0 3578.7 3579.2 3583.4 606.7 617.8 655.4 662.1 667.0 590.2 605.3 618.6 623.5 635.4 8008.7 8147.0 8205.1 8305.5 8429.3 495.7 504.9 532.5 591.3 593.5 3690.5 3746.4 3789.3 3791.5 3975.4 82984.0 83579.6 83738.6 83760.2 87788.6 76959.0 77424.0 78077.0 78335.6 79647.6 60455.1 62245.0 63492.0 63739.7 64085.5 7675.1 7675.6 7736.1 7801.7 7803.6 47606.2 47742.8 47750.3 47792.6 48471.5 58685.7 58912.0 62274.0 62943.7 63785.3 9831.6 10553.2 10790.6 10903.8 11264.6 11688.4 11795.3 11816.5 11863.6 11979.9 11187.0 11346.7 11486.1 11593.3 11612.1 11118.6 11169.8 11171.3 11269.5 11525.1 9288.7 10992.2 11069.6 11083.5 11189.7 9204.1 9382.8 9408.6 9436.6 9567.5 8987.7 9284.7 9598.7 9660.7 9677.3 09:37:12 INFO - PROCESS | 1985 | -------- Summary: end -------- 09:37:12 INFO - PROCESS | 1985 | 09:37:13 INFO - TEST-INFO | 1985: exit 0 09:37:13 INFO - TEST-OK | dromaeo_css | took 1112617ms 09:37:13 INFO - TEST-START | kraken 09:37:13 INFO - operating with platform_type : mac_ 09:37:13 INFO - Initialising browser for kraken test... 09:37:13 INFO - TEST-INFO | started process 1989 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpqpwYw6/profile http://localhost:49662/getInfo.html) 09:37:15 INFO - PROCESS | 1989 | __metrics Screen width/height:1600/1200 09:37:15 INFO - PROCESS | 1989 | colorDepth:24 09:37:15 INFO - PROCESS | 1989 | Browser inner width/height: 1024/689 09:37:15 INFO - PROCESS | 1989 | __metrics 09:37:15 INFO - PROCESS | 1989 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:37:15 ERROR - PROCESS | 1989 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:37:16 INFO - TEST-INFO | 1989: exit 0 09:37:16 INFO - Browser initialized. 09:37:16 INFO - Running cycle 1/1 for kraken test... 09:37:16 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpqpwYw6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:28 INFO - PROCESS | 1991 | RSS: Main: 236761088 09:37:28 INFO - PROCESS | 1991 | 09:37:30 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49662/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:31 INFO - PROCESS | 1991 | RSS: Main: 283357184 09:37:31 INFO - PROCESS | 1991 | 09:37:34 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49662/tests/kraken/driver.html?testName=audio-dft) 09:37:35 INFO - PROCESS | 1991 | RSS: Main: 294559744 09:37:35 INFO - PROCESS | 1991 | 09:37:38 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49662/tests/kraken/driver.html?testName=audio-fft) 09:37:39 INFO - PROCESS | 1991 | RSS: Main: 280178688 09:37:39 INFO - PROCESS | 1991 | 09:37:40 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49662/tests/kraken/driver.html?testName=audio-oscillator) 09:37:41 INFO - PROCESS | 1991 | RSS: Main: 281313280 09:37:41 INFO - PROCESS | 1991 | 09:37:43 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49662/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:37:44 INFO - PROCESS | 1991 | RSS: Main: 282542080 09:37:44 INFO - PROCESS | 1991 | 09:37:47 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49662/tests/kraken/driver.html?testName=imaging-darkroom) 09:37:47 INFO - PROCESS | 1991 | RSS: Main: 282550272 09:37:47 INFO - PROCESS | 1991 | 09:37:50 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49662/tests/kraken/driver.html?testName=imaging-desaturate) 09:37:51 INFO - PROCESS | 1991 | RSS: Main: 282570752 09:37:51 INFO - PROCESS | 1991 | 09:37:53 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49662/tests/kraken/driver.html?testName=json-parse-financial) 09:37:54 INFO - PROCESS | 1991 | RSS: Main: 282980352 09:37:54 INFO - PROCESS | 1991 | 09:37:56 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49662/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:37:56 INFO - PROCESS | 1991 | RSS: Main: 318001152 09:37:56 INFO - PROCESS | 1991 | 09:37:58 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49662/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:37:59 INFO - PROCESS | 1991 | RSS: Main: 317710336 09:37:59 INFO - PROCESS | 1991 | 09:38:01 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49662/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:38:01 INFO - PROCESS | 1991 | RSS: Main: 323534848 09:38:01 INFO - PROCESS | 1991 | 09:38:04 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49662/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:38:04 INFO - PROCESS | 1991 | RSS: Main: 324665344 09:38:04 INFO - PROCESS | 1991 | 09:38:07 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49662/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:08 INFO - PROCESS | 1991 | RSS: Main: 324812800 09:38:08 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49662/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49662/tests/kraken/driver.html?testName=ai-astar) 09:38:09 INFO - PROCESS | 1991 | RSS: Main: 388288512 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | __start_tp_report 09:38:09 INFO - PROCESS | 1991 | _x_x_mozilla_page_load 09:38:09 INFO - PROCESS | 1991 | _x_x_mozilla_page_load_details 09:38:09 INFO - PROCESS | 1991 | |i|pagename|runs| 09:38:09 INFO - PROCESS | 1991 | |0;ai-astar;110;109;108;110;110;112;117;109;109;111 09:38:09 INFO - PROCESS | 1991 | |1;audio-beat-detection;157;127;142;159;159;178;142;117;193;144 09:38:09 INFO - PROCESS | 1991 | |2;audio-dft;177;254;244;185;187;186;180;183;186;186 09:38:09 INFO - PROCESS | 1991 | |3;audio-fft;76;79;84;79;55;58;60;79;79;55 09:38:09 INFO - PROCESS | 1991 | |4;audio-oscillator;92;92;127;78;79;80;133;107;119;154 09:38:09 INFO - PROCESS | 1991 | |5;imaging-gaussian-blur;87;84;87;84;88;84;86;83;86;83 09:38:09 INFO - PROCESS | 1991 | |6;imaging-darkroom;94;103;134;93;92;92;94;94;93;93 09:38:09 INFO - PROCESS | 1991 | |7;imaging-desaturate;91;89;92;90;90;88;90;92;89;87 09:38:09 INFO - PROCESS | 1991 | |8;json-parse-financial;54;55;54;53;53;54;53;63;55;52 09:38:09 INFO - PROCESS | 1991 | |9;json-stringify-tinderbox;65;71;75;78;71;68;69;69;69;69 09:38:09 INFO - PROCESS | 1991 | |10;stanford-crypto-aes;85;84;85;84;83;82;82;84;83;83 09:38:09 INFO - PROCESS | 1991 | |11;stanford-crypto-ccm;121;132;124;135;121;127;125;124;122;133 09:38:09 INFO - PROCESS | 1991 | |12;stanford-crypto-pbkdf2;141;139;140;140;142;127;139;139;139;137 09:38:09 INFO - PROCESS | 1991 | |13;stanford-crypto-sha256-iterative;68;67;66;67;68;67;66;67;66;66 09:38:09 INFO - PROCESS | 1991 | __end_tp_report 09:38:09 INFO - PROCESS | 1991 | __start_cc_report 09:38:09 INFO - PROCESS | 1991 | _x_x_mozilla_cycle_collect,1260 09:38:09 INFO - PROCESS | 1991 | __end_cc_report 09:38:09 INFO - PROCESS | 1991 | __startTimestamp1460047089985__endTimestamp 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | ------- Summary: start ------- 09:38:09 INFO - PROCESS | 1991 | Number of tests: 14 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#0] ai-astar Cycles:10 Average:110.50 Median:110.00 stddev:2.55 (2.3%) stddev-sans-first:2.70 09:38:09 INFO - PROCESS | 1991 | Values: 110.0 109.0 108.0 110.0 110.0 112.0 117.0 109.0 109.0 111.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#1] audio-beat-detection Cycles:10 Average:151.80 Median:158.00 stddev:22.59 (14.3%) stddev-sans-first:23.88 09:38:09 INFO - PROCESS | 1991 | Values: 157.0 127.0 142.0 159.0 159.0 178.0 142.0 117.0 193.0 144.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#2] audio-dft Cycles:10 Average:196.80 Median:186.00 stddev:27.79 (14.9%) stddev-sans-first:28.54 09:38:09 INFO - PROCESS | 1991 | Values: 177.0 254.0 244.0 185.0 187.0 186.0 180.0 183.0 186.0 186.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#3] audio-fft Cycles:10 Average:70.40 Median:79.00 stddev:11.78 (14.9%) stddev-sans-first:12.32 09:38:09 INFO - PROCESS | 1991 | Values: 76.0 79.0 84.0 79.0 55.0 58.0 60.0 79.0 79.0 55.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#4] audio-oscillator Cycles:10 Average:106.10 Median:78.50 stddev:26.30 (33.5%) stddev-sans-first:27.40 09:38:09 INFO - PROCESS | 1991 | Values: 92.0 92.0 127.0 78.0 79.0 80.0 133.0 107.0 119.0 154.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.00 stddev:1.81 (2.1%) stddev-sans-first:1.80 09:38:09 INFO - PROCESS | 1991 | Values: 87.0 84.0 87.0 84.0 88.0 84.0 86.0 83.0 86.0 83.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#6] imaging-darkroom Cycles:10 Average:98.20 Median:93.00 stddev:12.98 (14.0%) stddev-sans-first:13.67 09:38:09 INFO - PROCESS | 1991 | Values: 94.0 103.0 134.0 93.0 92.0 92.0 94.0 94.0 93.0 93.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#7] imaging-desaturate Cycles:10 Average:89.80 Median:90.00 stddev:1.62 (1.8%) stddev-sans-first:1.66 09:38:09 INFO - PROCESS | 1991 | Values: 91.0 89.0 92.0 90.0 90.0 88.0 90.0 92.0 89.0 87.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.00 stddev:3.10 (5.7%) stddev-sans-first:3.28 09:38:09 INFO - PROCESS | 1991 | Values: 54.0 55.0 54.0 53.0 53.0 54.0 53.0 63.0 55.0 52.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.00 stddev:3.69 (5.3%) stddev-sans-first:3.35 09:38:09 INFO - PROCESS | 1991 | Values: 65.0 71.0 75.0 78.0 71.0 68.0 69.0 69.0 69.0 69.0 09:38:09 INFO - PROCESS | 1991 | 09:38:09 INFO - PROCESS | 1991 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:1.08 (1.3%) stddev-sans-first:1.00 09:38:09 INFO - PROCESS | 1991 | Values: 85.0 84.0 85.0 84.0 83.0 82.0 82.0 84.0 83.0 83.0 09:38:10 INFO - PROCESS | 1991 | 09:38:10 INFO - PROCESS | 1991 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:126.00 stddev:5.17 (4.1%) stddev-sans-first:5.10 09:38:10 INFO - PROCESS | 1991 | Values: 121.0 132.0 124.0 135.0 121.0 127.0 125.0 124.0 122.0 133.0 09:38:10 INFO - PROCESS | 1991 | 09:38:10 INFO - PROCESS | 1991 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.30 Median:139.50 stddev:4.19 (3.0%) stddev-sans-first:4.33 09:38:10 INFO - PROCESS | 1991 | Values: 141.0 139.0 140.0 140.0 142.0 127.0 139.0 139.0 139.0 137.0 09:38:10 INFO - PROCESS | 1991 | 09:38:10 INFO - PROCESS | 1991 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.71 09:38:10 INFO - PROCESS | 1991 | Values: 68.0 67.0 66.0 67.0 68.0 67.0 66.0 67.0 66.0 66.0 09:38:10 INFO - PROCESS | 1991 | -------- Summary: end -------- 09:38:10 INFO - PROCESS | 1991 | 09:38:10 INFO - TEST-INFO | 1991: exit 0 09:38:10 INFO - TEST-OK | kraken | took 57511ms 09:38:10 INFO - SUITE-END | took 1170s 09:38:11 INFO - Completed test suite (00:19:30) 09:38:11 INFO - javascript benchmark 09:38:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3398.337324221777, 3398.8274045454327, 3426.5888093428794, 3444.2422667013257, 3475.131217195701, 5561.944380556193, 5627.0, 5645.096784514473, 5650.660960342382, 5689.175069614908, 5589.832305030845, 5637.295338082739, 5650.406707295726, 5764.337101233359, 5784.884302313952, 5007.449180590151, 5040.42035165956, 5046.21783623539, 5075.238714192882, 5174.715390653512, 5180.507851754096, 5183.403908550527, 5212.374515058198, 5257.7108259045735, 5275.182346736262, 4987.675801072936, 5027.622928280393, 5112.079825631385, 5114.539691427753, 5114.846554603368, 5107.438181799999, 5111.0, 5116.974415127937, 5128.25640282157, 5131.87170320745, 5047.520485553867, 5080.974595127001, 5103.464136265676, 5124.436312005676, 5124.846254612367, 5440.945590544084, 5523.2543606613, 5566.359868615121, 5576.525995290405, 5613.887722245532, 6154.84612884677, 6164.198654174928, 6169.475594574466, 6173.47525460332, 6180.84547886302, 6147.72335244915, 6186.10301506279, 6195.473384762301, 6198.876022479571, 6207.068939659042, 5635.802746903839, 5729.341125770548, 5737.741801618937, 5761.0494268445655, 5810.070388737781, 6221.097940798559, 6232.688365581703, 6244.781432649836, 6249.937500625027, 6256.562040657202, 6032.125341825411, 6057.515398768176, 6111.388861113853, 6122.510199184055, 6147.508199344131, 6119.4798442132915, 6139.785107521216, 6182.783602573888, 6185.690715464209, 6200.472959798378, 5037.345145131109, 6175.3824617538785, 6178.938210617836, 6192.907106393318, 6193.102000209944, 6188.566800324024, 6198.101275315054, 6198.380161983766, 6211.503079753609, 6212.06818977157, 4799.232122860326, 4801.591864691471, 4846.539578740014, 4873.26900964851, 4917.434495033117, 3574.4459608760685, 4414.315781053878, 4428.756418397019, 4431.047324825178, 4449.688521803443, 5485.0, 5525.392206857243, 5534.806281780118, 5536.52939500147, 5544.085225937756, 6218.533609979269, 6237.09562113491, 6249.0626406039455, 6260.436560709558, 6269.028300613412, 5421.403645598981, 5477.123660214346, 5512.283403157564, 5547.972260138674, 5571.136473846479, 5303.787848486095, 5326.174442961347, 5326.840194794162, 5330.786768529216, 5339.5728341732565, 6206.627602343874, 6254.093156492283, 6254.15568898202, 6267.592606480564, 6279.183706118175, 6111.694415279129, 6144.877102457836, 6212.875742485216, 6247.375262473716, 6266.342034086538, 5240.423553409046, 6219.346968568235, 6231.750729970931, 6242.375762423721, 6270.372962703876, 6009.579329446897, 6155.230596175478, 6166.506679465542, 6189.288231853395, 6197.907031394442, 5992.250968628921, 6098.878022439438, 6112.938870611415, 6120.843333897588, 6137.232845894263, 6065.878682426415, 6072.028475443908, 6082.087686846831, 6086.60437071589, 6087.969560152171, 5868.882622347614, 5908.675022873783, 5981.611195272293, 6069.51443884497, 6071.241094863142, 4829.830955916526, 5906.675132867734, 5918.378570250234, 5920.230370051866, 5957.851053723692, 6018.8194354169445, 6061.636301821904, 6073.635581865102, 6081.422264884829, 6103.084537319438, 5766.798162064308, 5843.357230704704, 5877.4122587742595, 5895.469407753323, 5930.021546444788], "unit": "score", "name": "dojo.html", "value": 5618.438389286591}, {"lowerIsBetter": false, "replicates": [490.2694984473136, 548.6324162810917, 565.5503874419833, 630.1555915073801, 633.6894921488471, 38847.41728874069, 39255.0, 39317.8034109829, 39350.409743853794, 39354.2129157417, 42750.0, 43125.49060782873, 43190.568094319045, 43207.13585728283, 43218.783906080505, 9516.714498565038, 9717.416954982687, 9740.0, 9743.902560974406, 9754.902450975505, 9590.66432674853, 9740.025997400238, 9750.219982401391, 9846.015398460167, 9870.703878883609, 9860.161886239679, 9905.356151850143, 9907.157891579225, 9918.25613079022, 9918.3057185997, 9805.36265142767, 9872.55573499187, 9881.851772223423, 9889.554970026365, 9891.406515609051, 9706.805863882682, 9842.507874606312, 9859.408435493893, 9864.95067524665, 9889.950550247275, 41149.58850411487, 41503.58496415058, 41550.79224603888, 41634.583654163376, 41640.37539436911, 3009.623797025372, 3180.9204769880707, 3217.115293294339, 3361.83190840457, 3878.7866667333287, 24677.753222467723, 27622.033228836895, 29685.0, 29781.87290334527, 30578.235544111354, 2686.7313268672974, 2738.112325960083, 2780.902668406596, 2873.310405502685, 2901.7823663225345, 486182.0, 490314.5484272556, 490845.0, 491139.0, 491532.0, 62777.68611156915, 67967.66016169888, 68134.9779753304, 68241.65879170672, 68260.97608535876, 71230.28769712236, 71385.28614713787, 71573.64213179005, 71607.0, 71703.2829671707, 17484.300627974993, 17701.55746106332, 17713.822861771216, 17717.379891703986, 17741.93548387101, 11286.040686541572, 13166.736665266833, 13254.27101509407, 13257.204567725967, 13267.336633168303, 19325.0, 20858.47853803637, 20864.478388040116, 20972.79027209739, 21045.0, 6664.633445160563, 7344.48588598793, 7350.897365395126, 7431.777046688608, 7695.538267703956, 830.6345208108413, 945.3240932733129, 957.7189960874878, 1072.7103682005816, 1078.8166011778017, 28444.71555284462, 28528.857355713088, 29036.12891613255, 29145.56281655777, 29281.56077658837, 1054.4516851237468, 1066.4561073852235, 1085.6688709943385, 1160.2400427719856, 1161.8157863217266, 14530.346134423977, 14838.40646374133, 14962.850371496363, 15213.543593692208, 15321.46374876874, 541337.0, 541469.5853041419, 546634.0, 549577.0, 549578.0, 59555.0, 60051.69974150101, 60059.798804023674, 60138.699306504066, 60227.39772602218, 1315.8223639808696, 1329.4017692038622, 1430.3277459593974, 1477.062065588359, 1588.4281658602895, 29180.416391672472, 29383.559246611745, 29473.85263073671, 30101.397972040875, 30261.546076808427, 48753.0, 48984.0, 49311.0, 49327.7533612344, 49357.01285974189, 375032.0, 376779.0, 377198.0, 377615.0, 377901.0, 28726.85636571804, 32025.679743203204, 34330.970070897914, 34334.87981442243, 34415.65584344124, 40946.79526602348, 42383.0, 42516.0, 42831.785841070596, 42832.78583607062, 35032.82483587566, 35496.290074198885, 35696.28607427889, 36257.63742362543, 36382.454263185544, 34858.9542313731, 34892.0, 35656.465153023244, 35713.64286357103, 35729.64270357367, 35907.28185436329, 36004.09989750172, 36349.72775952829, 37007.25985480329, 37081.44377834281, 31350.74597016185, 31740.732040126346, 32114.678853211168, 32227.35545289034, 32246.548905299787, 21286.574268514232, 21844.344669659935, 24109.408732156986, 24250.87874560616, 24501.38746531351], "unit": "score", "name": "ext.html", "value": 19760.13181079007}, {"lowerIsBetter": false, "replicates": [364.3332701156883, 366.29853829915714, 367.5185506985847, 375.7520036775729, 379.15259395251627, 19512.31706890259, 20383.980800959966, 20708.0, 21107.788922110794, 21573.892130539363, 20114.09486573104, 20769.792302077014, 20943.37169884906, 21085.472863178427, 21139.154433822667, 5823.010088285002, 6063.333033366326, 6075.0, 6082.543809214305, 6113.449789518942, 5661.2923384576925, 6024.608400453978, 6033.637981721089, 6036.486898613623, 6040.879182416349, 5945.732442040118, 5973.163757073991, 5977.611455255416, 5979.820605381846, 5988.341282458948, 5640.689762063085, 6017.067354560028, 6017.217761690974, 6019.428154325332, 6032.396760323976, 5157.69053856766, 5183.688978661255, 5187.118189907725, 5193.402758682724, 5196.32447781786, 24087.397815054763, 24343.782810859386, 24700.011999519997, 24786.0, 24810.5037899241, 2374.5607062693457, 2415.589349810519, 2483.8137139714413, 2640.6435131257226, 2808.873600687974, 13263.50617382378, 13307.268100254481, 14380.424783008668, 14517.983741138125, 14705.926470367687, 1680.1851102215417, 1717.441831404786, 1733.0468242466593, 1752.7546143539914, 1794.605186858902, 79652.0, 93711.53144234236, 94078.5296073522, 98064.0193598062, 98253.52619710639, 10104.090631843022, 10441.373517588969, 10465.37207767521, 10529.631462898762, 10624.893751062546, 12785.296808675612, 12884.48462061507, 12920.289384083966, 12931.5473958411, 12940.482380704667, 8320.542370169698, 8480.10958849312, 8480.957595211985, 8589.699360522352, 8602.913970860336, 8776.385653004356, 8780.692675756445, 8790.86813697795, 8943.17068782863, 8947.597358118901, 5039.974800125976, 5881.617694849821, 5976.644375686231, 5986.281646202397, 5996.700164991733, 1692.1031853117868, 1786.1426515272742, 1808.9185986630632, 1814.392178620177, 1834.0462959261376, 835.9749207523784, 858.914108589136, 931.6739141300626, 1000.1198944928417, 1028.840529717895, 19764.325941482843, 19834.60330793376, 20373.694394584094, 20489.692654610193, 20854.58290834175, 934.9205317547949, 977.5405559387077, 978.8629591857145, 993.3146129170849, 1067.4716015572342, 11279.887201127884, 11423.543058277575, 11468.082553395709, 11487.368194749202, 11508.870858891132, 557358.0, 557718.0, 568846.0, 575666.0, 575847.0, 95024.0, 99274.0, 99455.50272248738, 99572.50641240388, 99853.50073249733, 1029.7683021320142, 1048.486241741541, 1059.782744537368, 1064.7923654887222, 1065.6430095917954, 17820.55448613795, 18001.459956201335, 18584.163712632693, 18672.86496213552, 19812.0, 50690.0, 52377.8081171652, 55207.44792552023, 56350.718246408505, 56366.43633563612, 70462.23844403726, 71171.64414177896, 71436.64281678558, 71807.0, 74244.2575574259, 7792.142864285038, 8032.0, 8168.060673022678, 11081.667549973514, 11340.505088880433, 10225.693229203136, 12080.818787718015, 12414.875851241733, 12771.361431928552, 13505.324733763076, 9997.60009599637, 10339.586416543254, 11074.501647426046, 11472.254303470581, 11758.58844940423, 9859.161971232526, 10052.0, 10452.947735261276, 10496.580136794442, 11002.449877505933, 10312.639057632947, 10833.404162770808, 11679.474423651127, 11873.910286891714, 11946.163768536118, 11609.955104040677, 11640.301581905112, 11742.647720568397, 11808.645740627795, 11814.232074915102, 11367.943160284145, 11423.543058277908, 11426.314421134759, 11784.70538236551, 11815.70460738454], "unit": "score", "name": "jquery.html", "value": 9652.999504046285}, {"lowerIsBetter": false, "replicates": [1313.7306852095319, 1423.4377420918743, 1582.905025698458, 1760.4982579964712, 1954.9120289586976, 7928.246816552432, 8593.054763975957, 8770.298376129911, 8783.253423459013, 8795.736127916156, 8471.941007374078, 8745.475271483716, 8755.299576033918, 8755.430896991691, 8789.077146899579, 2430.2587710748217, 2463.4210960424284, 2464.051340234005, 2466.075221791828, 2469.802415806731, 2384.4158181245534, 2410.252821625293, 2499.3626625210636, 2502.799776017914, 2505.386180385799, 2458.889349979246, 2480.0, 2495.301315631626, 2497.5629264878576, 2500.312414086132, 2444.9266522004277, 2474.814388920829, 2480.0575781203206, 2482.739312372211, 2493.675822143128, 2034.257496013959, 2235.58641651295, 2287.1651847075696, 2396.8202384821207, 2448.816338774599, 8802.427842190302, 8874.112588741138, 8878.245349145265, 8879.556022198864, 8907.510086945216, 5322.866928326784, 5491.972540137313, 5527.032769265403, 5531.225628412026, 5531.944680553183, 3509.9649003509894, 3564.180238545149, 3639.2357604903004, 3643.508126402928, 3645.2345007548447, 1014.0822555587132, 1038.657243109772, 1045.3936716704293, 1047.8218702820539, 1061.4162210784039, 11834.881651183465, 11941.641750747405, 11964.70088247792, 11982.921537061618, 11988.760224795455, 4773.212419950739, 4838.346823178826, 4912.477132089064, 4925.482824303468, 4928.630352723489, 10137.695869123938, 10142.797144057078, 10152.340097893613, 10157.136643385247, 10165.898341016644, 5481.177823326533, 5483.479069488392, 5489.752961116759, 5491.890162196733, 5497.038018346773, 4371.169477799272, 4565.954340456619, 4829.372181616367, 4944.085344211332, 4989.0, 3830.75100118497, 3900.9609903900596, 3904.882853514399, 3929.332013557702, 3955.7428767130045, 1300.7138429545485, 1307.7449897269942, 1311.0822424303026, 1320.5246111399888, 1326.290434617475, 961.6874515782259, 1003.0320740485466, 1102.2835157147917, 1246.1588427811191, 1391.325207274471, 7885.0, 7936.920630793734, 8062.879056814152, 8074.273315401525, 8130.268275855202, 1194.7072967122936, 1202.4709127983658, 1203.9097067719956, 1210.2315029955864, 1215.671768622467, 6198.597091189058, 6234.5635805494085, 6320.051992201206, 6322.083297921776, 6324.209473815773, 10376.844347334796, 10825.729356766144, 10895.0, 10995.725106872233, 10998.450077496092, 10439.060484556427, 10588.599814094867, 10674.519646615921, 10752.623658172082, 10809.405482698527, 8243.216894395024, 8413.200745929249, 8419.0, 8467.364947628952, 8491.490510569261, 7709.383249340151, 7881.605919703878, 7947.364210863001, 7948.0, 7960.800979975315, 9388.906110939077, 9632.133108020313, 9685.757856053655, 9740.707778766648, 9746.122848943629, 9842.21262299, 9858.014198580084, 9936.652217172365, 10018.998100189923, 10035.648752293635, 7368.59472729005, 7513.586752728653, 7612.391008719178, 7636.427267954815, 7642.7325043623205, 8224.0, 8283.420160588701, 8295.792605184677, 8305.95847020761, 8307.626156823078, 6244.594101383395, 7418.443616728659, 7533.096028476618, 7650.617469126632, 7684.3084122429245, 6954.408875245661, 7096.290370962863, 7131.393831524379, 7147.3210045045635, 7214.74748383804, 7328.560286382834, 7412.444066695128, 7416.851662966818, 7417.109946806418, 7430.554166749796, 6820.113385259884, 6893.896591551231, 6928.7228510859, 6936.618485983319, 6944.618046007518, 6883.105196324646, 7066.57600543969, 7069.82325441868, 7096.0420343253, 7102.751403700846], "unit": "score", "name": "mootools.html", "value": 4929.103228594239}, {"lowerIsBetter": false, "replicates": [317.49993759828277, 322.7079493058782, 323.1082213091867, 330.0478120622005, 332.9134425049487, 6211.689415529228, 6294.43350098491, 6353.332900045487, 6371.936280637192, 6424.3575642435735, 5544.306961629796, 5857.213549867289, 6240.001599744041, 6277.811665650038, 6302.495800335973, 4395.340698895159, 4475.440569928759, 4525.32120181972, 4538.364628951949, 4639.65202609804, 4571.337156112361, 4623.79192936317, 4631.39791827062, 4649.88375290617, 4659.906801863961, 4595.540445955411, 4596.0, 4598.057398233371, 4610.193216187154, 4617.145828021827, 4313.503947046099, 4420.138073075752, 4568.588827005586, 4570.748608826513, 4585.403897493321, 3995.62041606047, 3997.700172487075, 3997.8200980955635, 4001.5398229203433, 4009.9975006248437, 5940.227770389822, 5941.970290148522, 5947.732352044125, 5952.702364881782, 5966.164736936832, 5357.9819834231375, 5361.08861493547, 5369.650972686802, 5374.140137577969, 5383.730813459311, 6553.9344606553805, 6554.868902621968, 6558.1474408327085, 6558.475321974231, 6568.0, 4161.9375709364385, 4337.132573485284, 4458.687891847572, 4459.241928872067, 4467.709598876095, 6915.031895534629, 6961.477889158334, 7011.789646310568, 7017.3684368406575, 7052.823679408056, 4447.510773814878, 4456.7548784817145, 4462.598366147063, 4463.196624607602, 4466.6873318867365, 4534.59188673021, 4541.43232095988, 4546.999660074778, 4547.886302842456, 4547.976705241294, 4384.254676704968, 5328.094223981932, 5334.839390047037, 5339.359276886798, 5349.866253343697, 6434.871302573923, 6671.499637527205, 6735.8989615155815, 6757.560758550679, 6773.6613169341335, 1534.2252162657787, 1554.860062594372, 1564.6010267381744, 1564.984350156484, 1566.8746500279951, 909.2634965677822, 932.8060083093651, 993.1756641987278, 1009.4448053570507, 1012.5089331674133, 5366.973165134227, 5909.704514774244, 6043.274807023097, 6141.938580614226, 6155.261368635703, 5664.943350566524, 7049.295070493013, 7076.575405475688, 7079.89380159298, 7087.1849737279845, 6246.906296405557, 6253.6873156342, 6256.124142620037, 6269.68651567429, 6270.561060725706, 3668.284684486553, 3715.5727091384297, 3725.0873535983856, 3730.813459327023, 3732.0669832541867, 5826.76692932278, 7011.754003087305, 7211.639418029077, 7312.23221561739, 7312.268773122645, 7192.964035179791, 7250.963745181346, 7253.8186545336785, 7254.165770936304, 7269.89095163573, 7309.89035164473, 7317.5609463432365, 7318.780436586911, 7319.41444684424, 7330.853382932205, 6961.512694111364, 6991.510594258353, 7023.157221133292, 7037.064807178067, 7046.506744528039, 5547.141707527862, 6648.069270302065, 6919.757808476679, 6936.583804971718, 6946.826329341807, 7257.709691612276, 7292.197858235696, 7294.781156565312, 7309.634518274172, 7317.085364329459, 4472.418585583853, 4482.8655140345845, 4488.820447182076, 4489.865304040884, 4512.706674066175, 6985.476089293222, 6985.860282794214, 7006.684699188447, 7007.229204787572, 7012.439004879496, 6528.804135875931, 6729.899051514133, 6782.134912898992, 6802.0477133202385, 6810.489213309121, 5377.919331209957, 5388.56891448691, 6739.59562426256, 6756.628385438651, 6778.3221677831825, 6624.072629831731, 6630.76792312286, 6646.634435105922, 6653.833654158491, 6663.0, 6757.594544327356, 6761.560498567577, 6766.424853887278, 6775.0, 6780.830479238059, 6421.261554921244, 6638.203415589967, 6647.135872336566, 6653.800385988428, 6657.434118099822], "unit": "score", "name": "prototype.html", "value": 4891.277939007992}, {"lowerIsBetter": false, "replicates": [641.3266070625842, 659.7558903205814, 661.583202582373, 661.9073329733841, 694.8367133723577, 14647.414103435864, 14827.77758333626, 14846.925765371157, 14852.331645075985, 14854.925725371371, 13904.652383690413, 13967.720645587107, 13983.930080349584, 13987.930060349709, 15412.691746165097, 903.1645727701866, 910.6084383715, 919.0946917286501, 919.5494207838144, 920.7513971227764, 901.085398320705, 911.7811725185974, 912.2109375390273, 912.7489940266413, 914.095045904553, 963.9325247232697, 964.6093332200435, 966.1256562810668, 966.917811985982, 968.9903100969005, 953.1326492891435, 958.5255298627154, 960.9957594313959, 961.9130382667576, 962.5572236771094, 692.4045321023966, 719.2627556754317, 719.6905330707793, 720.2545365546634, 721.3435773446139, 15016.849831501655, 15061.698766024727, 15083.095014299177, 15107.169105699291, 15108.773368399483, 12332.260064396165, 12428.813567796491, 12447.813282800766, 12452.564160254438, 12463.18989265704, 8810.86783698245, 9936.0, 9976.451295178762, 10334.483275836254, 10347.223958203089, 3471.8090505022215, 3495.5805303363504, 3520.8591656333715, 3558.5907620623443, 3666.4500324951205, 57493.850122997304, 67602.0, 72457.63771181164, 81986.18013819846, 82944.17055829485, 9316.627334906663, 9350.158485736316, 9464.290178236659, 9496.050394960586, 10014.734871866223, 21043.68434473484, 21051.894740526506, 21058.368248952556, 21063.262785802424, 21125.577488450454, 7240.601766902765, 7558.811029724191, 7568.772936811904, 7619.009528761226, 7733.072031356161, 4165.687573432005, 4167.291560434734, 4213.304804707189, 4216.072464057902, 4320.783960801948, 3533.9823300883845, 3537.02731748766, 3578.6779189873037, 3579.2304654499408, 3583.4087375582735, 606.7451670298485, 617.7559863853843, 655.4068567946016, 662.1392190152782, 667.0128210248859, 590.2238556298454, 605.252513146264, 618.5639124417365, 623.5261201486902, 635.4217860316409, 8008.679652813938, 8147.0, 8205.097439281673, 8305.46014509055, 8429.32565394767, 495.6901936289819, 504.85106893466514, 532.4515748778764, 591.2845456996996, 593.5222146172373, 3690.5202323698286, 3746.4193050077283, 3789.2989796887664, 3791.450239715281, 3975.3639417693607, 82984.0, 83579.5821020891, 83738.58130709307, 83760.16239837524, 87788.56105719431, 76959.0, 77424.0, 78077.0, 78335.60832195917, 79647.60176199082, 60455.09317360243, 62245.0, 63492.047619285746, 63739.6813015932, 64085.50550768917, 7675.078990521173, 7675.61621918913, 7736.132830008339, 7801.726939557316, 7803.648835802289, 47606.23937606212, 47742.761286193345, 47750.283745743145, 47792.56622301337, 48471.5152848467, 58685.70657146687, 58912.0, 62274.0, 62943.6852815733, 63785.259326660824, 9831.606735730491, 10553.208509361983, 10790.622328218475, 10903.836442453528, 11264.605738799102, 11688.415579221175, 11795.29228246308, 11816.527338906348, 11863.644090677293, 11979.940100299442, 11187.0, 11346.716332091433, 11486.081113510732, 11593.304401735923, 11612.12909031809, 11118.61084862026, 11169.776604468028, 11171.329720216812, 11269.49287282058, 11525.135614828754, 9288.72133835986, 10992.230543861853, 11069.5572177112, 11083.501242443948, 11189.72025699364, 9204.125608067223, 9382.812343753223, 9408.576614052521, 9436.575354108943, 9567.469204927178, 8987.730368088967, 9284.72145835626, 9598.66404675833, 9660.659116885068, 9677.322587419094], "unit": "score", "name": "yui.html", "value": 6775.696071317477}], "name": "dromaeo_css", "value": 7479.538413147367}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 109.0, 108.0, 110.0, 110.0, 112.0, 117.0, 109.0, 109.0, 111.0], "unit": "score", "name": "ai-astar", "value": 110.5}, {"lowerIsBetter": true, "replicates": [157.0, 127.0, 142.0, 159.0, 159.0, 178.0, 142.0, 117.0, 193.0, 144.0], "unit": "score", "name": "audio-beat-detection", "value": 151.8}, {"lowerIsBetter": true, "replicates": [177.0, 254.0, 244.0, 185.0, 187.0, 186.0, 180.0, 183.0, 186.0, 186.0], "unit": "score", "name": "audio-dft", "value": 196.8}, {"lowerIsBetter": true, "replicates": [76.0, 79.0, 84.0, 79.0, 55.0, 58.0, 60.0, 79.0, 79.0, 55.0], "unit": "score", "name": "audio-fft", "value": 70.4}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 127.0, 78.0, 79.0, 80.0, 133.0, 107.0, 119.0, 154.0], "unit": "score", "name": "audio-oscillator", "value": 106.1}, {"lowerIsBetter": true, "replicates": [87.0, 84.0, 87.0, 84.0, 88.0, 84.0, 86.0, 83.0, 86.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 85.2}, {"lowerIsBetter": true, "replicates": [94.0, 103.0, 134.0, 93.0, 92.0, 92.0, 94.0, 94.0, 93.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 98.2}, {"lowerIsBetter": true, "replicates": [91.0, 89.0, 92.0, 90.0, 90.0, 88.0, 90.0, 92.0, 89.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 89.8}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 54.0, 53.0, 53.0, 54.0, 53.0, 63.0, 55.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 54.6}, {"lowerIsBetter": true, "replicates": [65.0, 71.0, 75.0, 78.0, 71.0, 68.0, 69.0, 69.0, 69.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 70.4}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 85.0, 84.0, 83.0, 82.0, 82.0, 84.0, 83.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.5}, {"lowerIsBetter": true, "replicates": [121.0, 132.0, 124.0, 135.0, 121.0, 127.0, 125.0, 124.0, 122.0, 133.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.4}, {"lowerIsBetter": true, "replicates": [141.0, 139.0, 140.0, 140.0, 142.0, 127.0, 139.0, 139.0, 139.0, 137.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 138.3}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 66.0, 67.0, 68.0, 67.0, 66.0, 67.0, 66.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.8}], "name": "kraken", "value": 1448.8000000000002}]} 09:38:11 INFO - Return code: 0 09:38:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:38:11 ERROR - # TBPL SUCCESS # 09:38:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:11 INFO - 2016-04-07 09:38:11.315 screenresolution[1995:12690] starting screenresolution argv=screenresolution get 09:38:11 INFO - 2016-04-07 09:38:11.317 screenresolution[1995:12690] Display 0: 1600x1200x32@60 09:38:11 INFO - 2016-04-07 09:38:11.323 screenresolution[1996:12693] starting screenresolution argv=screenresolution list 09:38:11 INFO - Available Modes on Display 0 09:38:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:11 INFO - Intel Iris: 09:38:11 INFO - Chipset Model: Intel Iris 09:38:11 INFO - Type: GPU 09:38:11 INFO - Bus: Built-In 09:38:11 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:11 INFO - Vendor: Intel (0x8086) 09:38:11 INFO - Device ID: 0x0a2e 09:38:11 INFO - Revision ID: 0x0009 09:38:11 INFO - Displays: 09:38:11 INFO - Display: 09:38:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:11 INFO - Display Serial Number: 1600x1200 60 09:38:11 INFO - Main Display: Yes 09:38:11 INFO - Mirror: Off 09:38:11 INFO - Online: Yes 09:38:11 INFO - Rotation: Supported 09:38:11 INFO - Return code: 0 09:38:11 INFO - Running post-action listener: _resource_record_post_action 09:38:11 INFO - Running post-run listener: _resource_record_post_run 09:38:12 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 135168; Write bytes: 692696576; Read time: 5; Write time: 1898 09:38:12 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381665792; Write bytes: 375612928; Read time: 28330; Write time: 682 09:38:12 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 135168; Write bytes: 297521152; Read time: 5; Write time: 1173 09:38:12 INFO - Running post-run listener: _upload_blobber_files 09:38:12 INFO - Blob upload gear active. 09:38:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:12 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:12 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:12 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:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:38:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:38:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:14 INFO - (blobuploader) - INFO - Done attempting. 09:38:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:38:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:38:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:15 INFO - (blobuploader) - INFO - Done attempting. 09:38:15 INFO - (blobuploader) - INFO - Iteration through files over. 09:38:15 INFO - Return code: 0 09:38:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:38:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:38:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8959d0e4441a1636dd6fae8cfdcda7bc53b83256d5cacaf2dcc02d8202bec956df027f45593d99ab328fe3e61d573502db8606f0208278923c729b3fdddb9a6c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85661fc46be281c8106d82c44c1aebf87cd4b0268be8768844128cbdf9b3f8148386f797da8653875591cefd04e7faff649ac5c42d0ef6b3ebebc0345f85fc2c"} 09:38:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:38:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:38:15 INFO - Contents: 09:38:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8959d0e4441a1636dd6fae8cfdcda7bc53b83256d5cacaf2dcc02d8202bec956df027f45593d99ab328fe3e61d573502db8606f0208278923c729b3fdddb9a6c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85661fc46be281c8106d82c44c1aebf87cd4b0268be8768844128cbdf9b3f8148386f797da8653875591cefd04e7faff649ac5c42d0ef6b3ebebc0345f85fc2c"} 09:38:15 INFO - Running post-run listener: copy_logs_to_upload_dir 09:38:15 INFO - Copying logs to upload dir... 09:38:15 INFO - mkdir: /builds/slave/test/build/upload/logs 09:38:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.957391 ========= master_lag: 0.87 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-04-07 09:38:16.044369) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 09:38:16.051376) ========= 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.qF7270s3e2/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.QRZsvTneCo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8959d0e4441a1636dd6fae8cfdcda7bc53b83256d5cacaf2dcc02d8202bec956df027f45593d99ab328fe3e61d573502db8606f0208278923c729b3fdddb9a6c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85661fc46be281c8106d82c44c1aebf87cd4b0268be8768844128cbdf9b3f8148386f797da8653875591cefd04e7faff649ac5c42d0ef6b3ebebc0345f85fc2c"} 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.011381 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459967170/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8959d0e4441a1636dd6fae8cfdcda7bc53b83256d5cacaf2dcc02d8202bec956df027f45593d99ab328fe3e61d573502db8606f0208278923c729b3fdddb9a6c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85661fc46be281c8106d82c44c1aebf87cd4b0268be8768844128cbdf9b3f8148386f797da8653875591cefd04e7faff649ac5c42d0ef6b3ebebc0345f85fc2c"}' ========= master_lag: 0.12 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 09:38:16.181298) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-07 09:38:16.181652) ========= 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.qF7270s3e2/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.QRZsvTneCo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.005180 ========= master_lag: 4.44 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-07 09:38:20.625081) ========= ========= Started reboot skipped (results: 3, elapsed: 5 secs) (at 2016-04-07 09:38:20.625397) ========= ========= Finished reboot skipped (results: 3, elapsed: 5 secs) (at 2016-04-07 09:38:25.694542) ========= ========= Total master_lag: 5.71 =========