builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0077 starttime: 1460046553.61 results: success (0) buildid: 20160407091415 builduid: bce76e528fe2401db6d5e37c9c151503 revision: 1725b460c3e0de97590cb8764df9ace9115b813e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.614497) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.614965) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.615315) ========= 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.ttRJV3XOs2/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.vabcG1vsSc/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.006080 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.644889) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.645237) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.662009) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.662336) ========= 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.ttRJV3XOs2/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.vabcG1vsSc/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005449 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.711034) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.711393) ========= 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:29:13.711810) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.715180) ========= 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.ttRJV3XOs2/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.vabcG1vsSc/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:13-- 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% 22.0M=0.001s 2016-04-07 09:29:13 (22.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165259 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.896479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.897226) ========= 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.ttRJV3XOs2/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.vabcG1vsSc/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.021193 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:13.949928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-07 09:29:13.950332) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1725b460c3e0de97590cb8764df9ace9115b813e --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 1725b460c3e0de97590cb8764df9ace9115b813e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ttRJV3XOs2/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.vabcG1vsSc/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:14,029 truncating revision to first 12 chars 2016-04-07 09:29:14,029 Setting DEBUG logging. 2016-04-07 09:29:14,029 attempt 1/10 2016-04-07 09:29:14,029 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1725b460c3e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-07 09:29:15,764 unpacking tar archive at: fx-team-1725b460c3e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.984336 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-07 09:29:15.954814) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:15.955230) ========= echo 1725b460c3e0de97590cb8764df9ace9115b813e in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'1725b460c3e0de97590cb8764df9ace9115b813e'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ttRJV3XOs2/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.vabcG1vsSc/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 1725b460c3e0de97590cb8764df9ace9115b813e program finished with exit code 0 elapsedTime=0.005095 script_repo_revision: '1725b460c3e0de97590cb8764df9ace9115b813e' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:16.005798) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:16.006152) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:16.021621) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-07 09:29:16.022254) ========= /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.ttRJV3XOs2/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.vabcG1vsSc/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:16 INFO - MultiFileLogger online at 20160407 09:29:16 in /builds/slave/test 09:29:16 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:29:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:29:16 INFO - {'append_to_log': False, 09:29:16 INFO - 'base_work_dir': '/builds/slave/test', 09:29:16 INFO - 'blob_upload_branch': 'Fx-Team', 09:29:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:29:16 INFO - 'branch': 'Fx-Team', 09:29:16 INFO - 'buildbot_json_path': 'buildprops.json', 09:29:16 INFO - 'config_files': ('talos/mac_config.py',), 09:29:16 INFO - 'default_actions': ('clobber', 09:29:16 INFO - 'read-buildbot-config', 09:29:16 INFO - 'download-and-extract', 09:29:16 INFO - 'populate-webroot', 09:29:16 INFO - 'create-virtualenv', 09:29:16 INFO - 'install', 09:29:16 INFO - 'run-tests'), 09:29:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:29:16 INFO - 'download_minidump_stackwalk': True, 09:29:16 INFO - 'download_symbols': 'ondemand', 09:29:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:29:16 INFO - 'tooltool.py': '/tools/tooltool.py', 09:29:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:29:16 INFO - '/tools/misc-python/virtualenv.py')}, 09:29:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:29:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:29:16 INFO - 'installer_path': 'installer.exe', 09:29:16 INFO - 'log_level': 'info', 09:29:16 INFO - 'log_name': 'talos', 09:29:16 INFO - 'log_to_console': True, 09:29:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:29:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:29:16 INFO - 'opt_config_files': (), 09:29:16 INFO - 'pip_index': False, 09:29:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:16 INFO - 'cmd': ('bash', 09:29:16 INFO - '-c', 09:29:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:16 INFO - 'enabled': True, 09:29:16 INFO - 'halt_on_failure': False, 09:29:16 INFO - 'name': 'check_screen_resolution'},), 09:29:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:16 INFO - 'cmd': ('bash', 09:29:16 INFO - '-c', 09:29:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:16 INFO - 'enabled': True, 09:29:16 INFO - 'halt_on_failure': False, 09:29:16 INFO - 'name': 'check_screen_resolution'},), 09:29:16 INFO - 'run_cmd_checks_enabled': True, 09:29:16 INFO - 'sps_profile': False, 09:29:16 INFO - 'sps_profile_interval': 0, 09:29:16 INFO - 'suite': 'dromaeojs', 09:29:16 INFO - 'system_bits': '32', 09:29:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:29:16 INFO - 'title': 't-yosemite-r7-0077', 09:29:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:29:16 INFO - 'use_talos_json': True, 09:29:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:29:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:29:16 INFO - 'work_dir': 'build'} 09:29:16 INFO - ##### 09:29:16 INFO - ##### Running clobber step. 09:29:16 INFO - ##### 09:29:16 INFO - Running pre-action listener: _resource_record_pre_action 09:29:16 INFO - Running main action method: clobber 09:29:16 INFO - rmtree: /builds/slave/test/build 09:29:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:29:18 INFO - Running post-action listener: _resource_record_post_action 09:29:18 INFO - ##### 09:29:18 INFO - ##### Running read-buildbot-config step. 09:29:18 INFO - ##### 09:29:18 INFO - Running pre-action listener: _resource_record_pre_action 09:29:18 INFO - Running main action method: read_buildbot_config 09:29:18 INFO - Using buildbot properties: 09:29:18 INFO - { 09:29:18 INFO - "project": "", 09:29:18 INFO - "product": "firefox", 09:29:18 INFO - "script_repo_revision": "production", 09:29:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:29:18 INFO - "repository": "", 09:29:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:29:18 INFO - "buildid": "20160407091415", 09:29:18 INFO - "stage_platform": "macosx64", 09:29:18 INFO - "basedir": "/builds/slave/test", 09:29:18 INFO - "buildnumber": 587, 09:29:18 INFO - "platform": "yosemite_r7", 09:29:18 INFO - "mozci_request": { 09:29:18 INFO - "priority": -1, 09:29:18 INFO - "type": "trigger_all_talos_jobs", 09:29:18 INFO - "times": 6 09:29:18 INFO - }, 09:29:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:29:18 INFO - "slavebuilddir": "test", 09:29:18 INFO - "branch": "fx-team", 09:29:18 INFO - "revision": "1725b460c3e0de97590cb8764df9ace9115b813e", 09:29:18 INFO - "slavename": "t-yosemite-r7-0077", 09:29:18 INFO - "builduid": "bce76e528fe2401db6d5e37c9c151503", 09:29:18 INFO - "repo_path": "integration/fx-team" 09:29:18 INFO - } 09:29:18 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg. 09:29:18 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json. 09:29:18 INFO - Running post-action listener: _resource_record_post_action 09:29:18 INFO - ##### 09:29:18 INFO - ##### Running download-and-extract step. 09:29:18 INFO - ##### 09:29:18 INFO - Running pre-action listener: _resource_record_pre_action 09:29:18 INFO - Running main action method: download_and_extract 09:29:18 INFO - mkdir: /builds/slave/test/build/tests 09:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json 09:29:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:29:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/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:29:18 INFO - Downloaded 1199 bytes. 09:29:18 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:29:18 INFO - Using the following test package requirements: 09:29:18 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:29:18 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:29:18 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:29:18 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'jsshell-mac.zip'], 09:29:18 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:29:18 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:29:18 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:29:18 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:29:18 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:29:18 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:18 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:29:18 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip 09:29:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:29:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/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:29:19 INFO - Downloaded 17970713 bytes. 09:29:19 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:29:19 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:29:22 INFO - Return code: 0 09:29:22 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:29:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip 09:29:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:29:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/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:29:23 INFO - Downloaded 17970713 bytes. 09:29:23 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:29:23 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:29:26 INFO - Return code: 0 09:29:26 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:29:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.talos.tests.zip 09:29:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:29:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/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:29:27 INFO - Downloaded 10847617 bytes. 09:29:27 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:29:27 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:29:27 INFO - Return code: 0 09:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:29:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 09:29:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 09:29:28 INFO - Downloaded 108918030 bytes. 09:29:28 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:29:28 INFO - mkdir: /builds/slave/test/properties 09:29:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:28 INFO - Contents: 09:29:28 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:29:29 INFO - Running post-action listener: _resource_record_post_action 09:29:29 INFO - Running post-action listener: set_extra_try_arguments 09:29:29 INFO - ##### 09:29:29 INFO - ##### Running populate-webroot step. 09:29:29 INFO - ##### 09:29:29 INFO - Running pre-action listener: _resource_record_pre_action 09:29:29 INFO - Running main action method: populate_webroot 09:29:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:29 INFO - '--tppagecycles', 09:29:29 INFO - '7'], 09:29:29 INFO - 'tests': ['tsvgm']}}, 09:29:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:29 INFO - 'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:29 INFO - 'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['damp', 'tps']}, 09:29:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:29 INFO - 'tests': ['damp', 'tps']}, 09:29:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['dromaeo_dom']}, 09:29:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['a11yr', 09:29:29 INFO - 'ts_paint', 09:29:29 INFO - 'tpaint', 09:29:29 INFO - 'sessionrestore', 09:29:29 INFO - 'sessionrestore_no_auto_restore', 09:29:29 INFO - 'tabpaint']}, 09:29:29 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:29 INFO - 'ts_paint', 09:29:29 INFO - 'tpaint', 09:29:29 INFO - 'sessionrestore', 09:29:29 INFO - 'sessionrestore_no_auto_restore', 09:29:29 INFO - 'tabpaint']}, 09:29:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:29 INFO - 'ts_paint', 09:29:29 INFO - 'tpaint', 09:29:29 INFO - 'sessionrestore', 09:29:29 INFO - 'sessionrestore_no_auto_restore', 09:29:29 INFO - 'tabpaint']}, 09:29:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:29 INFO - 'ts_paint', 09:29:29 INFO - 'tpaint', 09:29:29 INFO - 'sessionrestore', 09:29:29 INFO - 'sessionrestore_no_auto_restore', 09:29:29 INFO - 'tabpaint']}, 09:29:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['a11yr', 09:29:29 INFO - 'ts_paint', 09:29:29 INFO - 'tpaint', 09:29:29 INFO - 'sessionrestore', 09:29:29 INFO - 'sessionrestore_no_auto_restore', 09:29:29 INFO - 'tabpaint']}, 09:29:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['a11yr', 09:29:29 INFO - 'ts_paint', 09:29:29 INFO - 'tpaint', 09:29:29 INFO - 'sessionrestore', 09:29:29 INFO - 'sessionrestore_no_auto_restore', 09:29:29 INFO - 'tabpaint']}, 09:29:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['tsvgx', 09:29:29 INFO - 'tsvgr_opacity', 09:29:29 INFO - 'tart', 09:29:29 INFO - 'tscrollx', 09:29:29 INFO - 'cart']}, 09:29:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:29 INFO - 'tsvgr_opacity', 09:29:29 INFO - 'tart', 09:29:29 INFO - 'tscrollx', 09:29:29 INFO - 'cart']}, 09:29:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:29 INFO - 'talos_options': ['--disable-e10s'], 09:29:29 INFO - 'tests': ['tp5o']}, 09:29:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:29 INFO - 'tests': ['tp5o']}, 09:29:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:29 INFO - 'talos_options': ['--disable-e10s', 09:29:29 INFO - '--xperf_path', 09:29:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:29 INFO - 'tests': ['tp5n']}, 09:29:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:29 INFO - 'talos_options': ['--xperf_path', 09:29:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:29 INFO - 'tests': ['tp5n']}}, 09:29:29 INFO - 'talos.zip': {'path': '', 09:29:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:29 INFO - Running post-action listener: _resource_record_post_action 09:29:29 INFO - ##### 09:29:29 INFO - ##### Running create-virtualenv step. 09:29:29 INFO - ##### 09:29:29 INFO - Running pre-action listener: _resource_record_pre_action 09:29:29 INFO - Running main action method: create_virtualenv 09:29:29 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:29 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:29:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:29 INFO - Using real prefix '/tools/python27' 09:29:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:30 INFO - Installing distribute.............................................................................................................................................................................................done. 09:29:33 INFO - Installing pip.................done. 09:29:33 INFO - Return code: 0 09:29:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:29:33 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:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:33 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:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:33 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 0x105c12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c22420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af3c8ac40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, '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.ttRJV3XOs2/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.vabcG1vsSc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:33 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:29:33 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:29:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:29:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:33 INFO - 'HOME': '/Users/cltbld', 09:29:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:33 INFO - 'LOGNAME': 'cltbld', 09:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:33 INFO - 'MOZ_NO_REMOTE': '1', 09:29:33 INFO - 'NO_EM_RESTART': '1', 09:29:33 INFO - 'PAGER': '/bin/cat', 09:29:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:33 INFO - 'PWD': '/builds/slave/test', 09:29:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:33 INFO - 'SHELL': '/bin/bash', 09:29:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:29:33 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:29:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:33 INFO - 'USER': 'cltbld', 09:29:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:33 INFO - 'XPC_FLAGS': '0x0', 09:29:33 INFO - 'XPC_SERVICE_NAME': '0', 09:29:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:33 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:29:33 INFO - Cleaning up... 09:29:33 INFO - Return code: 0 09:29:33 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:29:33 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:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:33 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:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:33 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 0x105c12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c22420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af3c8ac40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, '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.ttRJV3XOs2/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.vabcG1vsSc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:33 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:29:33 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:29:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:29:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:33 INFO - 'HOME': '/Users/cltbld', 09:29:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:33 INFO - 'LOGNAME': 'cltbld', 09:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:33 INFO - 'MOZ_NO_REMOTE': '1', 09:29:33 INFO - 'NO_EM_RESTART': '1', 09:29:33 INFO - 'PAGER': '/bin/cat', 09:29:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:33 INFO - 'PWD': '/builds/slave/test', 09:29:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:33 INFO - 'SHELL': '/bin/bash', 09:29:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:29:33 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:29:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:33 INFO - 'USER': 'cltbld', 09:29:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:33 INFO - 'XPC_FLAGS': '0x0', 09:29:33 INFO - 'XPC_SERVICE_NAME': '0', 09:29:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:33 INFO - Downloading/unpacking psutil>=0.7.1 09:29:33 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:29:33 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:29:33 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:29:33 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:29:33 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:29:33 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:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:29:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:36 INFO - Installing collected packages: psutil 09:29:36 INFO - Running setup.py install for psutil 09:29:37 INFO - building 'psutil._psutil_osx' extension 09:29:37 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:29:37 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:29:37 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:29:37 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:29:37 INFO - building 'psutil._psutil_posix' extension 09:29:37 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:29:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:29:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:29:37 INFO - ^ 09:29:37 INFO - 1 warning generated. 09:29:37 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:29:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:37 INFO - Successfully installed psutil 09:29:37 INFO - Cleaning up... 09:29:37 INFO - Return code: 0 09:29:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:29:37 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:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:37 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:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:37 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 0x105c12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c22420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af3c8ac40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, '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.ttRJV3XOs2/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.vabcG1vsSc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:37 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:29:37 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:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:37 INFO - 'HOME': '/Users/cltbld', 09:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:37 INFO - 'LOGNAME': 'cltbld', 09:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:37 INFO - 'MOZ_NO_REMOTE': '1', 09:29:37 INFO - 'NO_EM_RESTART': '1', 09:29:37 INFO - 'PAGER': '/bin/cat', 09:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:37 INFO - 'PWD': '/builds/slave/test', 09:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:37 INFO - 'SHELL': '/bin/bash', 09:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:29:37 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:37 INFO - 'USER': 'cltbld', 09:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:37 INFO - 'XPC_FLAGS': '0x0', 09:29:37 INFO - 'XPC_SERVICE_NAME': '0', 09:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:29:38 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:29:38 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:29:38 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:29:38 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:29:38 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:29:38 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:29:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:29:40 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:29:40 INFO - Installing collected packages: mozsystemmonitor 09:29:40 INFO - Running setup.py install for mozsystemmonitor 09:29:40 INFO - Successfully installed mozsystemmonitor 09:29:40 INFO - Cleaning up... 09:29:40 INFO - Return code: 0 09:29:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:40 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 0x105c12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c22420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af3c8ac40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, '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.ttRJV3XOs2/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.vabcG1vsSc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:40 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:29:40 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:29:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:29:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:40 INFO - 'HOME': '/Users/cltbld', 09:29:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:40 INFO - 'LOGNAME': 'cltbld', 09:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:40 INFO - 'MOZ_NO_REMOTE': '1', 09:29:40 INFO - 'NO_EM_RESTART': '1', 09:29:40 INFO - 'PAGER': '/bin/cat', 09:29:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:40 INFO - 'PWD': '/builds/slave/test', 09:29:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:40 INFO - 'SHELL': '/bin/bash', 09:29:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:29:40 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:29:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:40 INFO - 'USER': 'cltbld', 09:29:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:40 INFO - 'XPC_FLAGS': '0x0', 09:29:40 INFO - 'XPC_SERVICE_NAME': '0', 09:29:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:41 INFO - Downloading/unpacking blobuploader==1.2.4 09:29:41 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:29:41 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:29:41 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:29:41 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:29:41 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:29:41 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:29:43 INFO - Downloading blobuploader-1.2.4.tar.gz 09:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:29:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:29: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:29: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:29: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:29: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:29: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:29: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:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:29:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:29:44 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:29:44 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:29:44 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:29:44 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:29:44 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:29:44 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:29:44 INFO - Downloading docopt-0.6.1.tar.gz 09:29:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:29:44 INFO - Installing collected packages: blobuploader, requests, docopt 09:29:44 INFO - Running setup.py install for blobuploader 09:29:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:29:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:29:44 INFO - Running setup.py install for requests 09:29:45 INFO - Running setup.py install for docopt 09:29:45 INFO - Successfully installed blobuploader requests docopt 09:29:45 INFO - Cleaning up... 09:29:45 INFO - Return code: 0 09:29:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:45 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:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:45 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:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:45 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 0x105c12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c22420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af3c8ac40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, '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.ttRJV3XOs2/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.vabcG1vsSc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:45 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:29:45 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:29:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:29:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:45 INFO - 'HOME': '/Users/cltbld', 09:29:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:45 INFO - 'LOGNAME': 'cltbld', 09:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:45 INFO - 'MOZ_NO_REMOTE': '1', 09:29:45 INFO - 'NO_EM_RESTART': '1', 09:29:45 INFO - 'PAGER': '/bin/cat', 09:29:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:45 INFO - 'PWD': '/builds/slave/test', 09:29:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:45 INFO - 'SHELL': '/bin/bash', 09:29:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:29:45 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:29:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:45 INFO - 'USER': 'cltbld', 09:29:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:45 INFO - 'XPC_FLAGS': '0x0', 09:29:45 INFO - 'XPC_SERVICE_NAME': '0', 09:29:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:45 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-fBq68_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-OrRGom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-FV6bgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-uUw0iI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-dSPd5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-kzVvyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-lT9ZNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-SVGzdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-7HVeg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-E92R8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-1H_d44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-t78t7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-21ptjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-bIu41t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-SgtX7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-5sCbYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Z8c2kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:29:47 INFO - Running setup.py install for manifestparser 09:29:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:29:47 INFO - Running setup.py install for mozcrash 09:29:47 INFO - Running setup.py install for mozdebug 09:29:48 INFO - Running setup.py install for mozdevice 09:29:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:29:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:29:48 INFO - Running setup.py install for mozfile 09:29:48 INFO - Running setup.py install for mozhttpd 09:29:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:29:48 INFO - Running setup.py install for mozinfo 09:29:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:29:48 INFO - Running setup.py install for mozInstall 09:29:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:29:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:29:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:29:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:29:48 INFO - Running setup.py install for mozleak 09:29:48 INFO - Running setup.py install for mozlog 09:29:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:29:49 INFO - Running setup.py install for moznetwork 09:29:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:29:49 INFO - Running setup.py install for mozprocess 09:29:49 INFO - Running setup.py install for mozprofile 09:29:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:29:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:29:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:29:49 INFO - Running setup.py install for mozrunner 09:29:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:29:49 INFO - Running setup.py install for mozscreenshot 09:29:49 INFO - Running setup.py install for moztest 09:29:50 INFO - Running setup.py install for mozversion 09:29:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:29:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:29:50 INFO - Cleaning up... 09:29:50 INFO - Return code: 0 09:29:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:50 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:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:50 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:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:50 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 0x105c12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c22420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af3c8ac40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, '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.ttRJV3XOs2/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.vabcG1vsSc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:50 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:29:50 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:29:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:29:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:50 INFO - 'HOME': '/Users/cltbld', 09:29:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:50 INFO - 'LOGNAME': 'cltbld', 09:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:50 INFO - 'MOZ_NO_REMOTE': '1', 09:29:50 INFO - 'NO_EM_RESTART': '1', 09:29:50 INFO - 'PAGER': '/bin/cat', 09:29:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:50 INFO - 'PWD': '/builds/slave/test', 09:29:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:50 INFO - 'SHELL': '/bin/bash', 09:29:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:29:50 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:29:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:50 INFO - 'USER': 'cltbld', 09:29:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:50 INFO - 'XPC_FLAGS': '0x0', 09:29:50 INFO - 'XPC_SERVICE_NAME': '0', 09:29:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-jk8Xqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:50 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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-O2NvlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:50 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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-MsBroZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:50 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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Y1owGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:50 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:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-r_MTh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:51 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:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-V4ZYFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:51 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:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-fsJEkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:51 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:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-QePe3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:51 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:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-st37u0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:51 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:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-pmWWkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:51 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:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-3m_Phm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:51 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:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-lMuvyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:51 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:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-1yjmWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:51 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:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-zcOKkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:52 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:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-O1FYO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:52 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:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-w4Z3sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:52 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:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-IT_tCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:52 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:29:52 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:29:52 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:29:52 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:29:52 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:29:52 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:29:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:29:52 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:29:52 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:29:52 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:29:52 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:29:52 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:29:52 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:29:54 INFO - Downloading blessings-1.6.tar.gz 09:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:29:54 INFO - Installing collected packages: blessings 09:29:54 INFO - Running setup.py install for blessings 09:29:55 INFO - Successfully installed blessings 09:29:55 INFO - Cleaning up... 09:29:55 INFO - Return code: 0 09:29:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:29:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:55 INFO - Reading from file tmpfile_stdout 09:29:55 INFO - Current package versions: 09:29:55 INFO - blessings == 1.6 09:29:55 INFO - blobuploader == 1.2.4 09:29:55 INFO - docopt == 0.6.1 09:29:55 INFO - manifestparser == 1.1 09:29:55 INFO - mozInstall == 1.12 09:29:55 INFO - mozcrash == 0.17 09:29:55 INFO - mozdebug == 0.1 09:29:55 INFO - mozdevice == 0.48 09:29:55 INFO - mozfile == 1.2 09:29:55 INFO - mozhttpd == 0.7 09:29:55 INFO - mozinfo == 0.9 09:29:55 INFO - mozleak == 0.1 09:29:55 INFO - mozlog == 3.1 09:29:55 INFO - moznetwork == 0.27 09:29:55 INFO - mozprocess == 0.22 09:29:55 INFO - mozprofile == 0.28 09:29:55 INFO - mozrunner == 6.11 09:29:55 INFO - mozscreenshot == 0.1 09:29:55 INFO - mozsystemmonitor == 0.0 09:29:55 INFO - moztest == 0.7 09:29:55 INFO - mozversion == 1.4 09:29:55 INFO - psutil == 3.1.1 09:29:55 INFO - requests == 1.2.3 09:29:55 INFO - wsgiref == 0.1.2 09:29:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:55 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:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:55 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:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:55 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 0x105c12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c22420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af3c8ac40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, '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.ttRJV3XOs2/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.vabcG1vsSc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:55 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:29:55 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:29:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:29:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:55 INFO - 'HOME': '/Users/cltbld', 09:29:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:55 INFO - 'LOGNAME': 'cltbld', 09:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:55 INFO - 'MOZ_NO_REMOTE': '1', 09:29:55 INFO - 'NO_EM_RESTART': '1', 09:29:55 INFO - 'PAGER': '/bin/cat', 09:29:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:55 INFO - 'PWD': '/builds/slave/test', 09:29:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:55 INFO - 'SHELL': '/bin/bash', 09:29:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:29:55 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:29:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:55 INFO - 'USER': 'cltbld', 09:29:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:55 INFO - 'XPC_FLAGS': '0x0', 09:29:55 INFO - 'XPC_SERVICE_NAME': '0', 09:29:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:55 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:29:55 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:29:55 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:29:55 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:29:55 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:29:55 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:29:55 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:29:55 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:29:55 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:29:55 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:29:55 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:29:55 INFO - Cleaning up... 09:29:55 INFO - Return code: 0 09:29:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:29:55 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:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:55 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:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:55 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 0x105c12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c22420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af3c8ac40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, '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.ttRJV3XOs2/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.vabcG1vsSc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:55 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:29:55 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:29:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:29:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:55 INFO - 'HOME': '/Users/cltbld', 09:29:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:55 INFO - 'LOGNAME': 'cltbld', 09:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:55 INFO - 'MOZ_NO_REMOTE': '1', 09:29:55 INFO - 'NO_EM_RESTART': '1', 09:29:55 INFO - 'PAGER': '/bin/cat', 09:29:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:55 INFO - 'PWD': '/builds/slave/test', 09:29:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:55 INFO - 'SHELL': '/bin/bash', 09:29:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:29:55 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:29:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:55 INFO - 'USER': 'cltbld', 09:29:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:55 INFO - 'XPC_FLAGS': '0x0', 09:29:55 INFO - 'XPC_SERVICE_NAME': '0', 09:29:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:55 INFO - Downloading/unpacking jsonschema 09:29:55 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:29:55 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:29:55 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:29:55 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:29:55 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:29:55 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:29:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:29:58 INFO - Downloading/unpacking functools32 (from jsonschema) 09:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:58 INFO - Downloading functools32-3.2.3-2.tar.gz 09:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:29:58 INFO - warning: no files found matching '*.txt' 09:29:58 INFO - no previously-included directories found matching 'build' 09:29:58 INFO - no previously-included directories found matching 'dist' 09:29:58 INFO - no previously-included directories found matching '.git*' 09:29:58 INFO - Installing collected packages: jsonschema, functools32 09:29:58 INFO - Running setup.py install for functools32 09:29:59 INFO - warning: no files found matching '*.txt' 09:29:59 INFO - no previously-included directories found matching 'build' 09:29:59 INFO - no previously-included directories found matching 'dist' 09:29:59 INFO - no previously-included directories found matching '.git*' 09:29:59 INFO - Successfully installed jsonschema functools32 09:29:59 INFO - Cleaning up... 09:29:59 INFO - Return code: 0 09:29:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:59 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 0x105c12a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c22420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af3c8ac40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, '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.ttRJV3XOs2/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.vabcG1vsSc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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:29:59 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:29:59 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:29:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:29:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:59 INFO - 'HOME': '/Users/cltbld', 09:29:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:59 INFO - 'LOGNAME': 'cltbld', 09:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:59 INFO - 'MOZ_NO_REMOTE': '1', 09:29:59 INFO - 'NO_EM_RESTART': '1', 09:29:59 INFO - 'PAGER': '/bin/cat', 09:29:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:59 INFO - 'PWD': '/builds/slave/test', 09:29:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:59 INFO - 'SHELL': '/bin/bash', 09:29:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:29:59 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:29:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:59 INFO - 'USER': 'cltbld', 09:29:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:59 INFO - 'XPC_FLAGS': '0x0', 09:29:59 INFO - 'XPC_SERVICE_NAME': '0', 09:29:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:59 INFO - Downloading/unpacking flake8 09:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:30:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:30: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:30: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:30: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:30: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:30: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:30: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:30:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:30:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:30:02 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:30:02 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:30:02 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:30:02 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:30:02 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:30:02 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:30:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:30:02 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:30:02 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:30:02 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:30:02 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:30:02 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:30:02 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:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:30:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:30:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:30:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:30:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:30:03 INFO - no previously-included directories found matching 'docs/_build' 09:30:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:30:03 INFO - Running setup.py install for pep8 09:30:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:30:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:30:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:30:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:30:03 INFO - no previously-included directories found matching 'docs/_build' 09:30:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:30:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:30:03 INFO - Cleaning up... 09:30:03 INFO - Return code: 0 09:30:03 INFO - Running post-action listener: _resource_record_post_action 09:30:03 INFO - Running post-action listener: _start_resource_monitoring 09:30:03 INFO - Starting resource monitoring. 09:30:03 INFO - ##### 09:30:03 INFO - ##### Running install step. 09:30:03 INFO - ##### 09:30:03 INFO - Running pre-action listener: _resource_record_pre_action 09:30:03 INFO - Running main action method: install 09:30:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:03 INFO - Reading from file tmpfile_stdout 09:30:03 INFO - Detecting whether we're running mozinstall >=1.0... 09:30:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:30:03 INFO - Reading from file tmpfile_stdout 09:30:03 INFO - Output received: 09:30:03 INFO - Usage: mozinstall [options] installer 09:30:03 INFO - Options: 09:30:03 INFO - -h, --help show this help message and exit 09:30:03 INFO - -d DEST, --destination=DEST 09:30:03 INFO - Directory to install application into. [default: 09:30:03 INFO - "/builds/slave/test"] 09:30:03 INFO - --app=APP Application being installed. [default: firefox] 09:30:03 INFO - mkdir: /builds/slave/test/build/application 09:30:03 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:30:03 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:30:37 INFO - Reading from file tmpfile_stdout 09:30:37 INFO - Output received: 09:30:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:30:37 INFO - Running post-action listener: _resource_record_post_action 09:30:37 INFO - ##### 09:30:37 INFO - ##### Running run-tests step. 09:30:37 INFO - ##### 09:30:37 INFO - Running pre-action listener: _resource_record_pre_action 09:30:37 INFO - Running main action method: run_tests 09:30:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:37 INFO - 2016-04-07 09:30:37.431 screenresolution[2605:69137] starting screenresolution argv=screenresolution get 09:30:37 INFO - 2016-04-07 09:30:37.434 screenresolution[2605:69137] Display 0: 1600x1200x32@60 09:30:37 INFO - 2016-04-07 09:30:37.440 screenresolution[2606:69140] starting screenresolution argv=screenresolution list 09:30:37 INFO - Available Modes on Display 0 09:30:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:37 INFO - Intel Iris: 09:30:37 INFO - Chipset Model: Intel Iris 09:30:37 INFO - Type: GPU 09:30:37 INFO - Bus: Built-In 09:30:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:37 INFO - Vendor: Intel (0x8086) 09:30:37 INFO - Device ID: 0x0a2e 09:30:37 INFO - Revision ID: 0x0009 09:30:37 INFO - Displays: 09:30:37 INFO - Display: 09:30:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:37 INFO - Display Serial Number: 1600x1200 60 09:30:37 INFO - Main Display: Yes 09:30:37 INFO - Mirror: Off 09:30:37 INFO - Online: Yes 09:30:37 INFO - Rotation: Supported 09:30:37 INFO - Return code: 0 09:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:30:37 INFO - Python 2.7.3 09:30:37 INFO - Return code: 0 09:30:37 INFO - grabbing minidump binary from tooltool 09:30:37 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:30:37 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 0x105ab1e00>, '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 0x1058dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105aacb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:30:37 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:30:37 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:30:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:30:37 INFO - Return code: 0 09:30:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:30:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:30:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:30:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:30:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:30:39 INFO - Return code: 0 09:30:39 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/1459966130/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--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:30:39 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/1459966130/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0077 --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:30:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttRJV3XOs2/Render', 09:30:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:39 INFO - 'HOME': '/Users/cltbld', 09:30:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:39 INFO - 'LOGNAME': 'cltbld', 09:30:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:30:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:39 INFO - 'MOZ_NO_REMOTE': '1', 09:30:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:30:39 INFO - 'NO_EM_RESTART': '1', 09:30:39 INFO - 'PAGER': '/bin/cat', 09:30:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:39 INFO - 'PWD': '/builds/slave/test', 09:30:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:30:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:39 INFO - 'SHELL': '/bin/bash', 09:30:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vabcG1vsSc/Listeners', 09:30:39 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:30:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:39 INFO - 'USER': 'cltbld', 09:30:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:39 INFO - 'XPC_FLAGS': '0x0', 09:30:39 INFO - 'XPC_SERVICE_NAME': '0', 09:30:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:39 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/1459966130/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--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:30:39 INFO - mozversion application_buildid: 20160406110850 09:30:39 INFO - mozversion application_changeset: 1725b460c3e0de97590cb8764df9ace9115b813e 09:30:39 INFO - mozversion application_display_name: Nightly 09:30:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:30:39 INFO - mozversion application_name: Firefox 09:30:39 INFO - mozversion application_remotingname: firefox 09:30:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:30:39 INFO - mozversion application_vendor: Mozilla 09:30:39 INFO - mozversion application_version: 48.0a1 09:30:39 INFO - mozversion platform_buildid: 20160406110850 09:30:39 INFO - mozversion platform_changeset: 1725b460c3e0de97590cb8764df9ace9115b813e 09:30:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:30:39 INFO - mozversion platform_version: 48.0a1 09:30:39 INFO - using testdate: 1460046639 09:30:39 INFO - actual date: 1460046639 09:30:39 INFO - starting webserver on 'localhost:50912' 09:30:39 INFO - SUITE-START | Running 2 tests 09:30:39 INFO - TEST-START | dromaeo_css 09:30:39 INFO - operating with platform_type : mac_ 09:30:39 INFO - Initialising browser for dromaeo_css test... 09:30:39 INFO - TEST-INFO | started process 2624 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpx3WM1q/profile http://localhost:50912/getInfo.html) 09:30:41 INFO - PROCESS | 2624 | __metrics Screen width/height:1600/1200 09:30:41 INFO - PROCESS | 2624 | colorDepth:24 09:30:41 INFO - PROCESS | 2624 | Browser inner width/height: 1024/689 09:30:41 INFO - PROCESS | 2624 | __metrics 09:30:41 INFO - PROCESS | 2624 | 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:30:41 ERROR - PROCESS | 2624 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:30:42 INFO - TEST-INFO | 2624: exit 0 09:30:42 INFO - Browser initialized. 09:30:42 INFO - Running cycle 1/1 for dromaeo_css test... 09:30:42 INFO - TEST-INFO | started process 2626 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpx3WM1q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:54 INFO - PROCESS | 2626 | RSS: Main: 236220416 09:30:54 INFO - PROCESS | 2626 | 09:31:44 INFO - PROCESS | 2626 | 1460046704961 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:33:55 INFO - PROCESS | 2626 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50912/tests/dromaeo/cssquery-ext.html) 09:33:56 INFO - PROCESS | 2626 | RSS: Main: 353816576 09:33:56 INFO - PROCESS | 2626 | 09:36:59 INFO - PROCESS | 2626 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-ext.html (next: http://localhost:50912/tests/dromaeo/cssquery-jquery.html) 09:36:59 INFO - PROCESS | 2626 | RSS: Main: 357658624 09:36:59 INFO - PROCESS | 2626 | 09:40:01 INFO - PROCESS | 2626 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50912/tests/dromaeo/cssquery-mootools.html) 09:40:02 INFO - PROCESS | 2626 | RSS: Main: 360304640 09:40:02 INFO - PROCESS | 2626 | 09:43:04 INFO - PROCESS | 2626 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50912/tests/dromaeo/cssquery-prototype.html) 09:43:05 INFO - PROCESS | 2626 | RSS: Main: 326574080 09:43:05 INFO - PROCESS | 2626 | 09:46:08 INFO - PROCESS | 2626 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50912/tests/dromaeo/cssquery-yui.html) 09:46:08 INFO - PROCESS | 2626 | RSS: Main: 326074368 09:46:08 INFO - PROCESS | 2626 | 09:49:11 INFO - PROCESS | 2626 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-yui.html (next: http://localhost:50912/tests/dromaeo/cssquery-dojo.html) 09:49:11 INFO - PROCESS | 2626 | RSS: Main: 386060288 09:49:11 INFO - PROCESS | 2626 | 09:49:11 INFO - PROCESS | 2626 | __start_tp_report 09:49:11 INFO - PROCESS | 2626 | _x_x_mozilla_page_load 09:49:11 INFO - PROCESS | 2626 | _x_x_mozilla_page_load_details 09:49:11 INFO - PROCESS | 2626 | |i|pagename|runs| 09:49:11 INFO - PROCESS | 2626 | |0;dojo.html;2835.872385742641;3238.7085162335393;3479.0258727556293;3481.529993450885;3491.2842867212225;5572.665640061599;5654.491095801377;5699.487046165844;5708.885822283557;5725.083986562151;5703.201551782743;5725.9141112883235;5754.251947246862;5768.451997060283;5849.356570777221;4504.639628829702;5131.538161565477;5158.509941555546;5174.741262936857;5264.473552644743;5219.399769026553;5231.581473482112;5254.7635356409055;5276.261323414725;5319.5478384337375;4304.289792184286;5070.74646267687;5103.311053007834;5162.483751624845;5215.374155101412;5174.560162386164;5175.171972484384;5180.948190518085;5184.429712731597;5201.453847346012;5133.922991155136;5159.14874045782;5167.018266529385;5176.249443830661;5195.350581177353;5482.780688772445;5603.075492543766;5621.8594535136535;5701.572382071361;5704.629199102045;6238.970569855968;6249.96875015622;6253.6873156342;6277.340879207709;6282.78010269643;6236.968815155895;6269.561130720851;6295.937040629581;6299.5905266158015;6304.779332723333;5666.631668941547;5738.942610573882;5760.279965004374;5856.267966504187;5857.062869940874;6315.213334991914;6335.3981371769605;6353.491720662336;6369.554131210771;6391.073294372383;6045.667488288186;6067.24159480065;6104.664243466563;6119.877602447926;6143.723532440961;6105.732692890381;6224.284207316216;6315.684215789284;6319.241690997018;6340.3342649022115;6209.6895155242055;6218.844528886814;6224.253089629274;6234;6240.781572644935;6064.181335519736;6246;6255.437010669061;6267.561270711098;6275.560710750295;4861.756912154449;4879.048585525788;4884.535969082932;4899.044686286211;4915.287283343967;4379.802908869108;4402.3176407656865;4418.248897687401;4443.133588950123;4444.222261104358;5422.945770542244;5588.105903055492;5617.466340697627;5624.493795558419;5651.039323315046;6182.752689892354;6197.101420294032;6302.716377763011;6304.180456540712;6341.746330146835;5586.832395028156;5590.636608620508;5601.159826026047;5603.299587551556;5604.775808967595;5278.736063196901;5356.571474282048;5359.062164121263;5381.650192737459;5389.353277606634;6266.060090986389;6314.02132669437;6323.841903952254;6329.1139240505445;6349.619022858551;6288.434040936155;6316.3367846377305;6319.399657032574;6324.272708638566;6348.619082855043;5235.502627250406;6241.095041218907;6334.271558770801;6338.809835704937;6357.205349331333;6055.818325450244;6124.838682352372;6140.293866205444;6151.384861513813;6167.290761562478;5319.361676598833;6178.876422471615;6222.222222222223;6272.717727702173;6280.591761535486;6104.755809767737;6113.14415981754;6117.235345581802;6135.233095863017;6147.538934580014;5924.081767326072;6030.7889223876955;6040.335563088145;6110.388961103854;6126.24286675468;5854.853628659318;5872.236609240771;5906.497947674496;5913.615614985184;5954.7320370582565;5969.70151492415;6101.938980610137;6111.7249723763425;6114.785982490592;6118.20463339763;5730.770769169187;5876.236089308362;5925.614835035709;5957.49361304294;5961.52307815365 09:49:11 INFO - PROCESS | 2626 | |1;ext.html;489.6474538332399;539.4659287305567;569.7236840132535;631.318176369521;638.1289539778202;38142.80928595357;38475.80762096189;38560.421593676045;38632.42051369232;38634.84095477137;41595.16809663805;41975;42031.78984105083;42050.789746051305;42087.57912420875;9470.958194598606;9680.177184939288;9703.466309352983;9705.078017588354;9719.396299610557;9556.665516706917;9706.854397184048;9741;9828.901710982906;9830.016998300149;9797.90202097977;9890.554925028355;9890.802183956317;9892.40645561265;9895;9777.706668799947;9847.50762461874;9864.802703945881;9865.852012219822;9875.30872838898;9660.71017869465;9738.464384458852;9767.609295628166;9772.26707996896;9782.413055216639;34225.288735563125;36727.81636091829;38504.22991540182;38942.80528597339;38970.61029389726;2911.3449473868423;3162.5572419861237;3227.483602623569;3228.542097510899;3239.7408207343356;27660.723392766016;28521;30237;30274.54588181179;30277.545836812464;2658.830114749504;2709.6612923384578;2829.137113180479;2835.277004363874;2839.673437554687;430872;431212;432738;433697;434696;61899.69050154721;62781.37218627755;63029;63103.6844815773;63189.05216421757;67388;67404;67538;67655;67715;16079.437219697254;16476.176191190392;16754.497365079067;16817.495475135765;16826;12770.169938953939;12796.488140474463;12844.229346239255;12866.614001579968;12877.098603097693;19700.70448943267;20879.582408352053;20971.8951405242;20977.580448390945;20978.160873565193;7392.334689877829;8592;8815.59429350801;8844.955775221082;8945.865812012826;984.8621193032981;1010.1060561403065;1061.8354155105972;1081.518724167738;1093.8796732359592;24579.754202458105;27447.725522744917;28599.28501787472;29228.26929326784;29460.852695736383;1092.7432053467428;1111.7276267314398;1144.713821544614;1167.0313639679046;1248.1886773597234;11913.16607837443;14518.419263229353;14554.417823287162;14694.706105877822;14975;530369;538251;543514;545255;545910;55855.603609910075;56239;56349.43650563442;56418.7179064102;56532.43467565354;1321.7951217561294;1445.1401416157369;1450.4560789703862;1461.8318893327403;1474.6165996840898;27936.580951286152;27966.580501292905;28525.572116418687;28728.281792955342;28902.855485722437;43643.78178109216;44082.77958610187;44107.338389923534;44255.77872110619;44268.33597495976;252012.73993629916;329156.3542182369;329323;329593;329972;27771.722282776915;32315.676843231267;32460.188495287806;32750;32848.671513284564;40092.79721608356;40255.798721006206;41738.58261417347;41930.790346048074;42011;26599.468010640066;32453.026409207763;33801.98594042183;34226.657733423344;34870.65129348674;28517.857410713645;29184.562231566968;29510;29587.704122959356;29944.101676949726;34544.82727586346;35094.12264693403;35410;35645.64354356527;35650.28699426049;24707.38231544154;26879.596806048317;31166.844165779024;31186;31188.844055780482;23472.53054938926;23644;23684.026354465066;23761.16835910735;24170.516589667757 09:49:11 INFO - PROCESS | 2626 | |2;jquery.html;335.98699919741983;351.6588908758505;356.5471850749547;362.851230995292;364.49153430963787;20013.599728005433;20393.796062039375;21459.57080858382;22113.889430552867;22120.668189977125;12007.639770806889;12300.5694800682;12303.32331721755;12324.507019719224;12336.383180840967;5293.04725149473;5383.488568585978;5511.255980442629;6126.254017604983;6146.231721034871;6086.208792856923;6105.908411373833;6132.080187971796;6138.232720909887;6148.784792532262;6126.571140020201;6333.714982825761;6384.07430922516;6578.407943285103;6630.966845165767;6455.128557644704;6539.803805885807;6548.607083574977;6561.671916404209;6564.868702625945;5534.114541673353;5753.7123143843055;5754.309482862083;5761.884762304772;5773.9422605773825;13111.934440327737;13132.934335328262;13140.080194386397;13325.60023199296;13502;1993.362124120282;2006.197520991615;2084.47888027993;2229.442639340165;2698.6491756071587;9579.568919398644;9739.805203895954;9775.364601300964;9896.95051524745;10099.394036357842;1679.9328026878911;1706.2663054886393;1711.3496871188868;1777.3423833181805;1800.4328636479468;67594.66202669004;102841.45737813938;103361.48319258356;105857.88492208284;108310.37534437068;8447.944006999125;10840.078593319657;10870.021698047214;10895.346279223113;11036.558537658404;13669.589912302647;13699;13703.520376786964;13706.52027179044;13719.794203086962;8128.9593552031865;8278.220182660636;8296.668133274723;8393.706220282262;8408.075111737704;8320.625571849281;8323.042850072197;8361.03848057469;8439.367047471464;8449;4329.112531930947;4340.587644173798;4342.913141737147;4352.673549483801;4393.626541743987;1756.7804024496938;1802.2520653928473;1803.8556915446732;1805.8645601579933;1808.3128411203922;919.8988111307751;946.4936259101348;961.8653388525612;978.5438549241455;994.4679596415883;17239.56901077483;17322.913385433243;19023.714644280346;19536.21855125779;19639.509012274808;883.650957871637;933.9719808405758;950.6482601437513;954.0602506531061;956.258899353007;9746.463944483121;9820.705378838646;10531;10703.44028449273;10724.41015744126;467910;527302;601945;614549;618270;72243.63878180576;92419.53790231005;113757;114999.47549041673;120979.79020209685;845.4377838737171;911.40758506971;960.4765402855461;997.3317877022413;998.8301988661945;17137.22882470267;17389.91305043467;17411.216495258;17816.732749008515;17922.91038544799;40448.595514044486;40493.797531012155;44844.10311793811;44962;47211.34760283374;63836.68081659562;65066;65213.369665758735;68324.97512537416;69198;7183.138023437012;7401.963725078601;7440.55356678601;7643.694252229849;7936.365090792839;11435.256708313933;12134.939325303318;12229.388530573613;12474.875251247371;12502.374881255719;8979.024614584781;10531.05220530156;10685.102453390384;10838.403887785931;10851.294665846695;10328.741781455223;10377.481125943521;10391.428471434143;10718.732031698957;10735.141188705042;10516;10551.103156231806;10729.141668666643;10988.285761425483;11177.16171287173;7534.924650753423;7683.692652293846;7781.805454863674;7823.2567906048835;8044.919550804651;10271.332363396356;10784.89215107839;10879.891201088205;11165.274257173258;11178.27341222818 09:49:11 INFO - PROCESS | 2626 | |3;mootools.html;1564.9139297338645;1594.8484893935076;1865.1513561329596;1936.138418403809;1994.0727561683814;8597.699080532182;8647;8781.121887811216;8785.516796576187;8793.472391656496;7761.107472640634;7888.96055519723;8480.66077356905;8570.228679418851;8654.134586541342;2430.368104292888;2462.581361168606;2464.0267094497663;2467.4571594249237;2471.864047477388;2189.7372315322104;2443.1693224303735;2530.063876365747;2542.8220024598286;2552.8468291902454;2167.2522979572022;2404.95190096198;2448.951020979588;2453.460238747473;2457.8279520433575;1996.620642078015;2412.7587241275905;2416.673749043883;2418.9274321770376;2421.225207933462;2404.4349577849193;2406.927792166238;2410.9035638574437;2411.2645643078854;2411.819113566472;8542.658293668246;8597.18326758957;8615;8622.913770862273;8764.474131552064;5243.973780131113;5395.595330350239;5420.83737487876;5431.2396264522995;5435.646682965595;3444.621091679913;3449.931001379958;3621.9094522636883;3622.6920711739535;3629;1045.6444808765057;1048.1457612046158;1071.276888100529;1072.2708558180434;1075.0539525217837;11676.299422034705;11884.10869184806;11887.465064072137;11894.048476121889;11959.94020029903;4789.544993225638;4903.411590609078;4979.6265280104135;4980.228064649913;4989.426215985136;10212.029857163558;10285.74285642865;10296.22778291631;10305.84541231882;10311.74220644475;5524.226608274845;5534.667919924817;5537.1140617501815;5545.085060964976;5548.250986116904;4950.381202349707;4975.353204083518;4980.352554167935;5014.373203349582;5015.122353588107;3750.4186851038135;3766.322062028806;3769.4157405602177;3825.6365645264214;3838.351537439158;1034.849946757716;1180.698921774959;1193.5106606291379;1237.5359240284893;1289.9419526121346;1006.5269323417982;1106.9391183484986;1167.1888037813637;1186.2704436771323;1200.1418985425462;7848.175941526058;7853;7942.967414236113;7956.84086318282;7962.124166341698;613.7452957022875;636.1920361141417;639.2201514152727;641.3746597067915;777.3159619534773;6150.630962142286;6210.130581718563;6244.219472565929;6247.656378899204;6268.937310626927;10017.549210285555;10398.324108932895;10498.10766084876;10508.316959397614;10508.632197873038;10012.799744005079;10356.792864142675;10367.688969330931;10372.377657340583;10389.013043760831;8014.679412823539;8153.3477321812925;8190.631421586043;8209.220124088202;8212.58937053145;7661.927330173891;7875.684972601031;7963.721269755531;7981.441299109007;8004.959975200087;9255.223432969948;9342.579583918876;9406.623735050522;9441.386309889835;9607.087326703951;9382.906170938477;9688;9763.414195148314;9792.85310720354;9832.852507212541;7377.926220737724;7415.184329723618;7503.174650788518;7521;7529;7998.3201427879285;8123.984501937258;8125.553094579855;8235.670573177005;8260.628271727666;7197.820054498679;7311.305425984523;7339.339459448462;7376.077990251219;7470.589117598584;5956.374580668968;7140.285971402818;7208.855822883618;7281.417486600953;7414.888776668462;6056.606320589148;7633.0458692663415;7637.350825179699;7639.159692433939;7642.885356719765;6777.356151165632;6923.89614155778;6936.861262774615;6937;6958.756443524452;6919.135108111486;7064.187618423742;7083.929160708533;7085.185203701641;7091.6099614519635 09:49:11 INFO - PROCESS | 2626 | |4;prototype.html;316.79408384550044;320.97753157278987;321.45031995288065;328.9210589458529;329.46790932643785;6292.685365731712;6381;6447.677616119198;6509.8698026039565;6511.544191906569;5395.001924643943;6389.265234498034;6444.677766111699;6467.353264673542;6472.7087281072345;4525.570070843265;4596.586307232356;4617.930731039037;4639.420072490939;4779.402574678165;4592.392662568108;4663.673542852001;4679.438467383919;4684.46128695201;4712.3873896393425;4648.65135114866;4651.581357677808;4665.370175026362;4686.601638860701;4687;3747.7257732371127;4617.515160908123;4675.555822196886;4676.672632915697;4702.388689470347;3312.9006129816144;3909.9413508797393;3990.2418540477506;4003;4020.718549701522;6095.756169753205;6121.785737499166;6124.142620033199;6141.048137538689;6198.256209254873;5564.940971840445;5589.105743081088;5589.608727389084;5596.356419011784;5606.439356064361;6818.897716534256;6847.17833859935;6854.794356169273;6857.417119544844;6872.244053154149;4596.057808149355;4608.216603177468;4610.124076425468;4620.283856002325;4621.861344159647;6975.3024697530345;7258.891116633255;7261.583991121734;7264.4914855960105;7296.343329100407;4461.174682683714;4527.592516673515;4541.8183272669385;4544.545545445429;4544.818207271738;4630.976845115752;4631.722096674211;4632.073585282957;4634.443866736014;4636.072785442925;4416.933745993812;5466.671999680032;5469.781208751605;5471.50756431923;5478.233047373371;6688.130543029375;6907.516473846883;6928.411085057725;6928.861422771516;6930.549514281555;1555.4322672224782;1596.3135851583932;1601.5355546891271;1601.591594143486;1607.3249235321193;978.9510524473748;1010.56545685356;1024.882138554076;1029.341221618165;1034.9689509314733;6268.686565671698;6270.122182894398;6275.6862156891975;6281.183446152062;6285.842853928689;6853.074834897225;7005.369516743517;7068.222495525488;7140.714371425189;7261.346478816932;6333.176687030748;6334.1765570475545;6343.682815859189;6344.175257216532;6351.364863513611;3749.343864823645;3799.1072098056934;3802.5627052888717;3808.352580061396;3810.161764411825;6182.948898687234;7455.478116531899;7459.813504662427;7462.96268518654;7485.513441626277;6877.518573699893;7081.610511421818;7369.520981136317;7370.373518250902;7372.963135184397;7354.852902941911;7392;7401.851962960711;7411.703531858665;7414.925850741639;7112.715491380287;7152.6066066364765;7161.176464706626;7166.570005799668;7181.569105853666;5692.886142277049;7014.964925175342;7034.648267586498;7057.894131587927;7071.82320441993;6630.5027122965;7073.0674372836165;7409.888851667337;7448.5158464699625;7471.476996610185;4177.851090949892;4540.091981603627;4575.290829921368;4584.747838868894;4597.724136551684;6890.517663763489;7174.533655312388;7176.995220669006;7178;7178.066851309296;6927.7228910843005;6939.097917270722;6939.410150137295;6940.826479338057;6942.51402401827;6648.501362397937;6927.134108236471;6931.68807403658;6965.547239429421;6974.4769142315545;6750.493712971449;6791.0832037676255;6806.591604503746;6808.523403361717;6814.93185068143;6964.025036494996;6976.127984002;6977.79066628002;6979.895301570582;6983.9650801745665;5224.895502089861;6693.761654094008;6739.393454589111;6798.32016798336;6824.590524568542 09:49:11 INFO - PROCESS | 2626 | |5;yui.html;650.5868773328936;677.976270830521;703.789482090804;732.8387754693969;758.7913323835946;15153.166575838324;15417.69164616707;15423.537293881187;15429.459968901077;15429.919905606612;15573.065616063044;15943.601409964756;15974;15978.920105399486;15982.760258596101;731.7365748330596;898.7887846356117;910.3400034974657;919.3472634429563;920.5443305563755;735.7682330066039;901.24745837226;916.1251005289932;916.8670542771295;917.536643994785;943.1652987106391;943.3915124744535;946.8153710026547;946.9715908522755;949.9835176361296;755.029786724057;940.6331530703031;942.2509105261367;942.4910548303908;945.2390825385562;711.7580022792224;712.2094475132599;714.6998260730454;715.5456285258898;715.9355657990806;15338.233088345538;15413.614659633487;15415.845841541553;15435.768463473058;15437.0737755735;12185.32980686062;12217.389130543437;12390.690232744164;12483.687907802378;12484.50061997519;9182.632694692204;9296.163345298955;9412.62349506019;9635.325527213097;9811.411315321033;3414.607320158189;3465.2549701814232;3489.110276879405;3507.2459421224557;3728.086618778417;75755;76472.61763691202;76886;76886.61556692237;80793.59603202005;9338.859917101248;9419.811603768023;9431.858522122173;9452.338336316392;10026.498675066217;20312.28906988278;20510.589788204452;20512.58974820525;20526;20530.897345513178;6413.358664133642;7380.667869945865;7402.888956665654;7763.262490063435;7807.180246074194;4052.169305292408;4090.9590904091174;4121.258173528794;4123.835046598162;4132.33882578786;3557.5197348358156;3579.785212887235;3593.1735700788963;3599.082234030358;3599.4420864766003;624.7625902157242;633.224300232221;643.3148696638066;648.1929997153479;653.7744478155029;452.5316297632001;590.4479311843354;597.9342272350038;604.6130476495047;625.3995470561114;7555.962220188864;7754.418418618513;7840.864233926787;8010.639521221559;8408.915910840813;484.6776893365872;545.8689914420511;588.2705445247861;618;624.1012941364421;3690.372636651776;3849.634284742945;3856.1709232515145;3871.3225185592974;3916.4712763776524;63667;82491.60916012405;86114.56942715247;87980.12019879719;88047;76059.23940760522;76213;76378;76975;77314;66416;68543.65728171327;68924.96612550816;69595;69763.95354069694;6323.798478289111;7746.767596972099;7777.494462859896;7852.646630901509;7971.80070498219;46569.76715116403;47975.76012119917;48815.77960551015;48856.267155991976;49159.262611061575;63700.36299636944;67149;67256;67411.66294168498;68190.3180968184;8212.824825581822;10128.20064624704;10210.081092701692;10277.331973421704;10418;9109.90890091117;10819.945900270448;11058.723531911766;11308.038816700673;11493.712657183638;10377;10423.53094110782;10529.526171322155;10694;11072.335659860435;9920;10350.637727679496;10458.215633827645;10593.311434756715;11424.48589813477;10290.948545257226;10314.43270620123;10670.519826607666;10904.836427453436;10960.958708922639;8605.268552173135;8638.13618638131;8740.344474164336;8840.071792461957;8913;8718.298567366624;8893.866592000995;8952.641894324415;9101.180893719596;9165.908340916505 09:49:11 INFO - PROCESS | 2626 | __end_tp_report 09:49:11 INFO - PROCESS | 2626 | __start_cc_report 09:49:11 INFO - PROCESS | 2626 | _x_x_mozilla_cycle_collect,1246 09:49:11 INFO - PROCESS | 2626 | __end_cc_report 09:49:11 INFO - PROCESS | 2626 | __startTimestamp1460047751603__endTimestamp 09:49:11 INFO - PROCESS | 2626 | 09:49:11 INFO - PROCESS | 2626 | ------- Summary: start ------- 09:49:11 INFO - PROCESS | 2626 | Number of tests: 6 09:49:11 INFO - PROCESS | 2626 | 09:49:11 INFO - PROCESS | 2626 | [#0] dojo.html Cycles:165 Average:5713.03 Median:5913.62 stddev:669.92 (11.3%) stddev-sans-first:632.81 09:49:11 INFO - PROCESS | 2626 | Values: 2835.9 3238.7 3479.0 3481.5 3491.3 5572.7 5654.5 5699.5 5708.9 5725.1 5703.2 5725.9 5754.3 5768.5 5849.4 4504.6 5131.5 5158.5 5174.7 5264.5 5219.4 5231.6 5254.8 5276.3 5319.5 4304.3 5070.7 5103.3 5162.5 5215.4 5174.6 5175.2 5180.9 5184.4 5201.5 5133.9 5159.1 5167.0 5176.2 5195.4 5482.8 5603.1 5621.9 5701.6 5704.6 6239.0 6250.0 6253.7 6277.3 6282.8 6237.0 6269.6 6295.9 6299.6 6304.8 5666.6 5738.9 5760.3 5856.3 5857.1 6315.2 6335.4 6353.5 6369.6 6391.1 6045.7 6067.2 6104.7 6119.9 6143.7 6105.7 6224.3 6315.7 6319.2 6340.3 6209.7 6218.8 6224.3 6234.0 6240.8 6064.2 6246.0 6255.4 6267.6 6275.6 4861.8 4879.0 4884.5 4899.0 4915.3 4379.8 4402.3 4418.2 4443.1 4444.2 5422.9 5588.1 5617.5 5624.5 5651.0 6182.8 6197.1 6302.7 6304.2 6341.7 5586.8 5590.6 5601.2 5603.3 5604.8 5278.7 5356.6 5359.1 5381.7 5389.4 6266.1 6314.0 6323.8 6329.1 6349.6 6288.4 6316.3 6319.4 6324.3 6348.6 5235.5 6241.1 6334.3 6338.8 6357.2 6055.8 6124.8 6140.3 6151.4 6167.3 5319.4 6178.9 6222.2 6272.7 6280.6 6104.8 6113.1 6117.2 6135.2 6147.5 5924.1 6030.8 6040.3 6110.4 6126.2 5854.9 5872.2 5906.5 5913.6 5954.7 5969.7 6101.9 6111.7 6114.8 6118.2 5730.8 5876.2 5925.6 5957.5 5961.5 09:49:11 INFO - PROCESS | 2626 | 09:49:11 INFO - PROCESS | 2626 | [#1] ext.html Cycles:180 Average:57665.52 Median:34982.39 stddev:116875.19 (334.1%) stddev-sans-first:117124.21 09:49:11 INFO - PROCESS | 2626 | Values: 489.6 539.5 569.7 631.3 638.1 38142.8 38475.8 38560.4 38632.4 38634.8 41595.2 41975.0 42031.8 42050.8 42087.6 9471.0 9680.2 9703.5 9705.1 9719.4 9556.7 9706.9 9741.0 9828.9 9830.0 9797.9 9890.6 9890.8 9892.4 9895.0 9777.7 9847.5 9864.8 9865.9 9875.3 9660.7 9738.5 9767.6 9772.3 9782.4 34225.3 36727.8 38504.2 38942.8 38970.6 2911.3 3162.6 3227.5 3228.5 3239.7 27660.7 28521.0 30237.0 30274.5 30277.5 2658.8 2709.7 2829.1 2835.3 2839.7 430872.0 431212.0 432738.0 433697.0 434696.0 61899.7 62781.4 63029.0 63103.7 63189.1 67388.0 67404.0 67538.0 67655.0 67715.0 16079.4 16476.2 16754.5 16817.5 16826.0 12770.2 12796.5 12844.2 12866.6 12877.1 19700.7 20879.6 20971.9 20977.6 20978.2 7392.3 8592.0 8815.6 8845.0 8945.9 984.9 1010.1 1061.8 1081.5 1093.9 24579.8 27447.7 28599.3 29228.3 29460.9 1092.7 1111.7 1144.7 1167.0 1248.2 11913.2 14518.4 14554.4 14694.7 14975.0 530369.0 538251.0 543514.0 545255.0 545910.0 55855.6 56239.0 56349.4 56418.7 56532.4 1321.8 1445.1 1450.5 1461.8 1474.6 27936.6 27966.6 28525.6 28728.3 28902.9 43643.8 44082.8 44107.3 44255.8 44268.3 252012.7 329156.4 329323.0 329593.0 329972.0 27771.7 32315.7 32460.2 32750.0 32848.7 40092.8 40255.8 41738.6 41930.8 42011.0 26599.5 32453.0 33802.0 34226.7 34870.7 28517.9 29184.6 29510.0 29587.7 29944.1 34544.8 35094.1 35410.0 35645.6 35650.3 24707.4 26879.6 31166.8 31186.0 31188.8 23472.5 23644.0 23684.0 23761.2 24170.5 09:49:11 INFO - PROCESS | 2626 | 09:49:11 INFO - PROCESS | 2626 | [#2] jquery.html Cycles:180 Average:31495.90 Median:22411.46 stddev:94266.05 (420.6%) stddev-sans-first:94501.45 09:49:11 INFO - PROCESS | 2626 | Values: 336.0 351.7 356.5 362.9 364.5 20013.6 20393.8 21459.6 22113.9 22120.7 12007.6 12300.6 12303.3 12324.5 12336.4 5293.0 5383.5 5511.3 6126.3 6146.2 6086.2 6105.9 6132.1 6138.2 6148.8 6126.6 6333.7 6384.1 6578.4 6631.0 6455.1 6539.8 6548.6 6561.7 6564.9 5534.1 5753.7 5754.3 5761.9 5773.9 13111.9 13132.9 13140.1 13325.6 13502.0 1993.4 2006.2 2084.5 2229.4 2698.6 9579.6 9739.8 9775.4 9897.0 10099.4 1679.9 1706.3 1711.3 1777.3 1800.4 67594.7 102841.5 103361.5 105857.9 108310.4 8447.9 10840.1 10870.0 10895.3 11036.6 13669.6 13699.0 13703.5 13706.5 13719.8 8129.0 8278.2 8296.7 8393.7 8408.1 8320.6 8323.0 8361.0 8439.4 8449.0 4329.1 4340.6 4342.9 4352.7 4393.6 1756.8 1802.3 1803.9 1805.9 1808.3 919.9 946.5 961.9 978.5 994.5 17239.6 17322.9 19023.7 19536.2 19639.5 883.7 934.0 950.6 954.1 956.3 9746.5 9820.7 10531.0 10703.4 10724.4 467910.0 527302.0 601945.0 614549.0 618270.0 72243.6 92419.5 113757.0 114999.5 120979.8 845.4 911.4 960.5 997.3 998.8 17137.2 17389.9 17411.2 17816.7 17922.9 40448.6 40493.8 44844.1 44962.0 47211.3 63836.7 65066.0 65213.4 68325.0 69198.0 7183.1 7402.0 7440.6 7643.7 7936.4 11435.3 12134.9 12229.4 12474.9 12502.4 8979.0 10531.1 10685.1 10838.4 10851.3 10328.7 10377.5 10391.4 10718.7 10735.1 10516.0 10551.1 10729.1 10988.3 11177.2 7534.9 7683.7 7781.8 7823.3 8044.9 10271.3 10784.9 10879.9 11165.3 11178.3 09:49:11 INFO - PROCESS | 2626 | 09:49:11 INFO - PROCESS | 2626 | [#3] mootools.html Cycles:180 Average:5973.20 Median:5529.45 stddev:3157.99 (57.1%) stddev-sans-first:3149.47 09:49:11 INFO - PROCESS | 2626 | Values: 1564.9 1594.8 1865.2 1936.1 1994.1 8597.7 8647.0 8781.1 8785.5 8793.5 7761.1 7889.0 8480.7 8570.2 8654.1 2430.4 2462.6 2464.0 2467.5 2471.9 2189.7 2443.2 2530.1 2542.8 2552.8 2167.3 2405.0 2449.0 2453.5 2457.8 1996.6 2412.8 2416.7 2418.9 2421.2 2404.4 2406.9 2410.9 2411.3 2411.8 8542.7 8597.2 8615.0 8622.9 8764.5 5244.0 5395.6 5420.8 5431.2 5435.6 3444.6 3449.9 3621.9 3622.7 3629.0 1045.6 1048.1 1071.3 1072.3 1075.1 11676.3 11884.1 11887.5 11894.0 11959.9 4789.5 4903.4 4979.6 4980.2 4989.4 10212.0 10285.7 10296.2 10305.8 10311.7 5524.2 5534.7 5537.1 5545.1 5548.3 4950.4 4975.4 4980.4 5014.4 5015.1 3750.4 3766.3 3769.4 3825.6 3838.4 1034.8 1180.7 1193.5 1237.5 1289.9 1006.5 1106.9 1167.2 1186.3 1200.1 7848.2 7853.0 7943.0 7956.8 7962.1 613.7 636.2 639.2 641.4 777.3 6150.6 6210.1 6244.2 6247.7 6268.9 10017.5 10398.3 10498.1 10508.3 10508.6 10012.8 10356.8 10367.7 10372.4 10389.0 8014.7 8153.3 8190.6 8209.2 8212.6 7661.9 7875.7 7963.7 7981.4 8005.0 9255.2 9342.6 9406.6 9441.4 9607.1 9382.9 9688.0 9763.4 9792.9 9832.9 7377.9 7415.2 7503.2 7521.0 7529.0 7998.3 8124.0 8125.6 8235.7 8260.6 7197.8 7311.3 7339.3 7376.1 7470.6 5956.4 7140.3 7208.9 7281.4 7414.9 6056.6 7633.0 7637.4 7639.2 7642.9 6777.4 6923.9 6936.9 6937.0 6958.8 6919.1 7064.2 7083.9 7085.2 7091.6 09:49:11 INFO - PROCESS | 2626 | 09:49:11 INFO - PROCESS | 2626 | [#4] prototype.html Cycles:180 Average:5602.37 Median:6338.93 stddev:1802.20 (28.4%) stddev-sans-first:1763.05 09:49:11 INFO - PROCESS | 2626 | Values: 316.8 321.0 321.5 328.9 329.5 6292.7 6381.0 6447.7 6509.9 6511.5 5395.0 6389.3 6444.7 6467.4 6472.7 4525.6 4596.6 4617.9 4639.4 4779.4 4592.4 4663.7 4679.4 4684.5 4712.4 4648.7 4651.6 4665.4 4686.6 4687.0 3747.7 4617.5 4675.6 4676.7 4702.4 3312.9 3909.9 3990.2 4003.0 4020.7 6095.8 6121.8 6124.1 6141.0 6198.3 5564.9 5589.1 5589.6 5596.4 5606.4 6818.9 6847.2 6854.8 6857.4 6872.2 4596.1 4608.2 4610.1 4620.3 4621.9 6975.3 7258.9 7261.6 7264.5 7296.3 4461.2 4527.6 4541.8 4544.5 4544.8 4631.0 4631.7 4632.1 4634.4 4636.1 4416.9 5466.7 5469.8 5471.5 5478.2 6688.1 6907.5 6928.4 6928.9 6930.5 1555.4 1596.3 1601.5 1601.6 1607.3 979.0 1010.6 1024.9 1029.3 1035.0 6268.7 6270.1 6275.7 6281.2 6285.8 6853.1 7005.4 7068.2 7140.7 7261.3 6333.2 6334.2 6343.7 6344.2 6351.4 3749.3 3799.1 3802.6 3808.4 3810.2 6182.9 7455.5 7459.8 7463.0 7485.5 6877.5 7081.6 7369.5 7370.4 7373.0 7354.9 7392.0 7401.9 7411.7 7414.9 7112.7 7152.6 7161.2 7166.6 7181.6 5692.9 7015.0 7034.6 7057.9 7071.8 6630.5 7073.1 7409.9 7448.5 7471.5 4177.9 4540.1 4575.3 4584.7 4597.7 6890.5 7174.5 7177.0 7178.0 7178.1 6927.7 6939.1 6939.4 6940.8 6942.5 6648.5 6927.1 6931.7 6965.5 6974.5 6750.5 6791.1 6806.6 6808.5 6814.9 6964.0 6976.1 6977.8 6979.9 6984.0 5224.9 6693.8 6739.4 6798.3 6824.6 09:49:11 INFO - PROCESS | 2626 | 09:49:11 INFO - PROCESS | 2626 | [#5] yui.html Cycles:180 Average:17570.90 Median:649.39 stddev:24459.72 (3766.6%) stddev-sans-first:24495.34 09:49:11 INFO - PROCESS | 2626 | Values: 650.6 678.0 703.8 732.8 758.8 15153.2 15417.7 15423.5 15429.5 15429.9 15573.1 15943.6 15974.0 15978.9 15982.8 731.7 898.8 910.3 919.3 920.5 735.8 901.2 916.1 916.9 917.5 943.2 943.4 946.8 947.0 950.0 755.0 940.6 942.3 942.5 945.2 711.8 712.2 714.7 715.5 715.9 15338.2 15413.6 15415.8 15435.8 15437.1 12185.3 12217.4 12390.7 12483.7 12484.5 9182.6 9296.2 9412.6 9635.3 9811.4 3414.6 3465.3 3489.1 3507.2 3728.1 75755.0 76472.6 76886.0 76886.6 80793.6 9338.9 9419.8 9431.9 9452.3 10026.5 20312.3 20510.6 20512.6 20526.0 20530.9 6413.4 7380.7 7402.9 7763.3 7807.2 4052.2 4091.0 4121.3 4123.8 4132.3 3557.5 3579.8 3593.2 3599.1 3599.4 624.8 633.2 643.3 648.2 653.8 452.5 590.4 597.9 604.6 625.4 7556.0 7754.4 7840.9 8010.6 8408.9 484.7 545.9 588.3 618.0 624.1 3690.4 3849.6 3856.2 3871.3 3916.5 63667.0 82491.6 86114.6 87980.1 88047.0 76059.2 76213.0 76378.0 76975.0 77314.0 66416.0 68543.7 68925.0 69595.0 69764.0 6323.8 7746.8 7777.5 7852.6 7971.8 46569.8 47975.8 48815.8 48856.3 49159.3 63700.4 67149.0 67256.0 67411.7 68190.3 8212.8 10128.2 10210.1 10277.3 10418.0 9109.9 10819.9 11058.7 11308.0 11493.7 10377.0 10423.5 10529.5 10694.0 11072.3 9920.0 10350.6 10458.2 10593.3 11424.5 10290.9 10314.4 10670.5 10904.8 10961.0 8605.3 8638.1 8740.3 8840.1 8913.0 8718.3 8893.9 8952.6 9101.2 9165.9 09:49:11 INFO - PROCESS | 2626 | -------- Summary: end -------- 09:49:11 INFO - PROCESS | 2626 | 09:49:12 INFO - TEST-INFO | 2626: exit 0 09:49:12 INFO - TEST-OK | dromaeo_css | took 1112851ms 09:49:12 INFO - TEST-START | kraken 09:49:12 INFO - operating with platform_type : mac_ 09:49:12 INFO - Initialising browser for kraken test... 09:49:12 INFO - TEST-INFO | started process 2628 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpgtsbgg/profile http://localhost:50912/getInfo.html) 09:49:14 INFO - PROCESS | 2628 | __metrics Screen width/height:1600/1200 09:49:14 INFO - PROCESS | 2628 | colorDepth:24 09:49:14 INFO - PROCESS | 2628 | Browser inner width/height: 1024/689 09:49:14 INFO - PROCESS | 2628 | __metrics 09:49:14 INFO - PROCESS | 2628 | 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:49:14 ERROR - PROCESS | 2628 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:49:14 INFO - TEST-INFO | 2628: exit 0 09:49:14 INFO - Browser initialized. 09:49:14 INFO - Running cycle 1/1 for kraken test... 09:49:14 INFO - TEST-INFO | started process 2630 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpgtsbgg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:26 INFO - PROCESS | 2630 | RSS: Main: 236224512 09:49:26 INFO - PROCESS | 2630 | 09:49:29 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:30 INFO - PROCESS | 2630 | RSS: Main: 282558464 09:49:30 INFO - PROCESS | 2630 | 09:49:32 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-dft) 09:49:33 INFO - PROCESS | 2630 | RSS: Main: 293175296 09:49:33 INFO - PROCESS | 2630 | 09:49:36 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-fft) 09:49:37 INFO - PROCESS | 2630 | RSS: Main: 278458368 09:49:37 INFO - PROCESS | 2630 | 09:49:39 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-oscillator) 09:49:39 INFO - PROCESS | 2630 | RSS: Main: 280281088 09:49:39 INFO - PROCESS | 2630 | 09:49:41 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50912/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:49:42 INFO - PROCESS | 2630 | RSS: Main: 281206784 09:49:42 INFO - PROCESS | 2630 | 09:49:45 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50912/tests/kraken/driver.html?testName=imaging-darkroom) 09:49:45 INFO - PROCESS | 2630 | RSS: Main: 281108480 09:49:45 INFO - PROCESS | 2630 | 09:49:48 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50912/tests/kraken/driver.html?testName=imaging-desaturate) 09:49:49 INFO - PROCESS | 2630 | RSS: Main: 282587136 09:49:49 INFO - PROCESS | 2630 | 09:49:51 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50912/tests/kraken/driver.html?testName=json-parse-financial) 09:49:52 INFO - PROCESS | 2630 | RSS: Main: 282939392 09:49:52 INFO - PROCESS | 2630 | 09:49:54 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50912/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:49:54 INFO - PROCESS | 2630 | RSS: Main: 317775872 09:49:54 INFO - PROCESS | 2630 | 09:49:56 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:49:57 INFO - PROCESS | 2630 | RSS: Main: 318099456 09:49:57 INFO - PROCESS | 2630 | 09:49:59 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:49:59 INFO - PROCESS | 2630 | RSS: Main: 323522560 09:49:59 INFO - PROCESS | 2630 | 09:50:02 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:03 INFO - PROCESS | 2630 | RSS: Main: 324550656 09:50:03 INFO - PROCESS | 2630 | 09:50:05 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:50:06 INFO - PROCESS | 2630 | RSS: Main: 324542464 09:50:06 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50912/tests/kraken/driver.html?testName=ai-astar) 09:50:08 INFO - PROCESS | 2630 | RSS: Main: 387952640 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | __start_tp_report 09:50:08 INFO - PROCESS | 2630 | _x_x_mozilla_page_load 09:50:08 INFO - PROCESS | 2630 | _x_x_mozilla_page_load_details 09:50:08 INFO - PROCESS | 2630 | |i|pagename|runs| 09:50:08 INFO - PROCESS | 2630 | |0;ai-astar;111;108;112;112;112;112;115;111;111;111 09:50:08 INFO - PROCESS | 2630 | |1;audio-beat-detection;161;127;145;163;119;146;149;145;164;144 09:50:08 INFO - PROCESS | 2630 | |2;audio-dft;187;183;186;187;187;186;185;185;186;185 09:50:08 INFO - PROCESS | 2630 | |3;audio-fft;79;75;86;82;60;58;61;81;79;55 09:50:08 INFO - PROCESS | 2630 | |4;audio-oscillator;96;96;130;78;77;78;122;80;79;124 09:50:08 INFO - PROCESS | 2630 | |5;imaging-gaussian-blur;92;90;89;89;94;87;94;85;94;84 09:50:08 INFO - PROCESS | 2630 | |6;imaging-darkroom;92;94;93;94;93;93;93;94;92;93 09:50:08 INFO - PROCESS | 2630 | |7;imaging-desaturate;86;86;86;88;88;86;85;89;88;94 09:50:08 INFO - PROCESS | 2630 | |8;json-parse-financial;54;55;55;53;54;54;53;65;54;53 09:50:08 INFO - PROCESS | 2630 | |9;json-stringify-tinderbox;64;65;76;77;73;70;71;70;70;71 09:50:08 INFO - PROCESS | 2630 | |10;stanford-crypto-aes;82;84;84;86;83;84;83;83;83;86 09:50:08 INFO - PROCESS | 2630 | |11;stanford-crypto-ccm;123;138;131;126;144;145;132;123;122;134 09:50:08 INFO - PROCESS | 2630 | |12;stanford-crypto-pbkdf2;143;131;141;139;162;129;140;139;138;137 09:50:08 INFO - PROCESS | 2630 | |13;stanford-crypto-sha256-iterative;67;67;68;97;88;88;94;97;95;66 09:50:08 INFO - PROCESS | 2630 | __end_tp_report 09:50:08 INFO - PROCESS | 2630 | __start_cc_report 09:50:08 INFO - PROCESS | 2630 | _x_x_mozilla_cycle_collect,1317 09:50:08 INFO - PROCESS | 2630 | __end_cc_report 09:50:08 INFO - PROCESS | 2630 | __startTimestamp1460047808463__endTimestamp 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | ------- Summary: start ------- 09:50:08 INFO - PROCESS | 2630 | Number of tests: 14 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.72 (1.5%) stddev-sans-first:1.81 09:50:08 INFO - PROCESS | 2630 | Values: 111.0 108.0 112.0 112.0 112.0 112.0 115.0 111.0 111.0 111.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#1] audio-beat-detection Cycles:10 Average:146.30 Median:147.50 stddev:14.69 (10.0%) stddev-sans-first:14.59 09:50:08 INFO - PROCESS | 2630 | Values: 161.0 127.0 145.0 163.0 119.0 146.0 149.0 145.0 164.0 144.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#2] audio-dft Cycles:10 Average:185.70 Median:186.00 stddev:1.25 (0.7%) stddev-sans-first:1.24 09:50:08 INFO - PROCESS | 2630 | Values: 187.0 183.0 186.0 187.0 187.0 186.0 185.0 185.0 186.0 185.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#3] audio-fft Cycles:10 Average:71.60 Median:79.00 stddev:11.70 (14.8%) stddev-sans-first:12.10 09:50:08 INFO - PROCESS | 2630 | Values: 79.0 75.0 86.0 82.0 60.0 58.0 61.0 81.0 79.0 55.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:78.50 stddev:21.53 (27.4%) stddev-sans-first:22.83 09:50:08 INFO - PROCESS | 2630 | Values: 96.0 96.0 130.0 78.0 77.0 78.0 122.0 80.0 79.0 124.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:91.00 stddev:3.71 (4.1%) stddev-sans-first:3.84 09:50:08 INFO - PROCESS | 2630 | Values: 92.0 90.0 89.0 89.0 94.0 87.0 94.0 85.0 94.0 84.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 09:50:08 INFO - PROCESS | 2630 | Values: 92.0 94.0 93.0 94.0 93.0 93.0 93.0 94.0 92.0 93.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#7] imaging-desaturate Cycles:10 Average:87.60 Median:88.00 stddev:2.59 (2.9%) stddev-sans-first:2.68 09:50:08 INFO - PROCESS | 2630 | Values: 86.0 86.0 86.0 88.0 88.0 86.0 85.0 89.0 88.0 94.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:54.00 stddev:3.59 (6.6%) stddev-sans-first:3.79 09:50:08 INFO - PROCESS | 2630 | Values: 54.0 55.0 55.0 53.0 54.0 54.0 53.0 65.0 54.0 53.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:4.11 (5.8%) stddev-sans-first:3.57 09:50:08 INFO - PROCESS | 2630 | Values: 64.0 65.0 76.0 77.0 73.0 70.0 71.0 70.0 70.0 71.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:1.32 (1.6%) stddev-sans-first:1.22 09:50:08 INFO - PROCESS | 2630 | Values: 82.0 84.0 84.0 86.0 83.0 84.0 83.0 83.0 83.0 86.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#11] stanford-crypto-ccm Cycles:10 Average:131.80 Median:133.00 stddev:8.51 (6.4%) stddev-sans-first:8.41 09:50:08 INFO - PROCESS | 2630 | Values: 123.0 138.0 131.0 126.0 144.0 145.0 132.0 123.0 122.0 134.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.90 Median:139.50 stddev:8.89 (6.4%) stddev-sans-first:9.36 09:50:08 INFO - PROCESS | 2630 | Values: 143.0 131.0 141.0 139.0 162.0 129.0 140.0 139.0 138.0 137.0 09:50:08 INFO - PROCESS | 2630 | 09:50:08 INFO - PROCESS | 2630 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.70 Median:91.00 stddev:13.87 (15.2%) stddev-sans-first:13.50 09:50:08 INFO - PROCESS | 2630 | Values: 67.0 67.0 68.0 97.0 88.0 88.0 94.0 97.0 95.0 66.0 09:50:08 INFO - PROCESS | 2630 | -------- Summary: end -------- 09:50:08 INFO - PROCESS | 2630 | 09:50:09 INFO - TEST-INFO | 2630: exit 0 09:50:09 INFO - TEST-OK | kraken | took 56998ms 09:50:09 INFO - SUITE-END | took 1169s 09:50:09 INFO - Completed test suite (00:19:29) 09:50:09 INFO - javascript benchmark 09:50:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2835.872385742641, 3238.7085162335393, 3479.0258727556293, 3481.529993450885, 3491.2842867212225, 5572.665640061599, 5654.491095801377, 5699.487046165844, 5708.885822283557, 5725.083986562151, 5703.201551782743, 5725.9141112883235, 5754.251947246862, 5768.451997060283, 5849.356570777221, 4504.639628829702, 5131.538161565477, 5158.509941555546, 5174.741262936857, 5264.473552644743, 5219.399769026553, 5231.581473482112, 5254.7635356409055, 5276.261323414725, 5319.5478384337375, 4304.289792184286, 5070.74646267687, 5103.311053007834, 5162.483751624845, 5215.374155101412, 5174.560162386164, 5175.171972484384, 5180.948190518085, 5184.429712731597, 5201.453847346012, 5133.922991155136, 5159.14874045782, 5167.018266529385, 5176.249443830661, 5195.350581177353, 5482.780688772445, 5603.075492543766, 5621.8594535136535, 5701.572382071361, 5704.629199102045, 6238.970569855968, 6249.96875015622, 6253.6873156342, 6277.340879207709, 6282.78010269643, 6236.968815155895, 6269.561130720851, 6295.937040629581, 6299.5905266158015, 6304.779332723333, 5666.631668941547, 5738.942610573882, 5760.279965004374, 5856.267966504187, 5857.062869940874, 6315.213334991914, 6335.3981371769605, 6353.491720662336, 6369.554131210771, 6391.073294372383, 6045.667488288186, 6067.24159480065, 6104.664243466563, 6119.877602447926, 6143.723532440961, 6105.732692890381, 6224.284207316216, 6315.684215789284, 6319.241690997018, 6340.3342649022115, 6209.6895155242055, 6218.844528886814, 6224.253089629274, 6234.0, 6240.781572644935, 6064.181335519736, 6246.0, 6255.437010669061, 6267.561270711098, 6275.560710750295, 4861.756912154449, 4879.048585525788, 4884.535969082932, 4899.044686286211, 4915.287283343967, 4379.802908869108, 4402.3176407656865, 4418.248897687401, 4443.133588950123, 4444.222261104358, 5422.945770542244, 5588.105903055492, 5617.466340697627, 5624.493795558419, 5651.039323315046, 6182.752689892354, 6197.101420294032, 6302.716377763011, 6304.180456540712, 6341.746330146835, 5586.832395028156, 5590.636608620508, 5601.159826026047, 5603.299587551556, 5604.775808967595, 5278.736063196901, 5356.571474282048, 5359.062164121263, 5381.650192737459, 5389.353277606634, 6266.060090986389, 6314.02132669437, 6323.841903952254, 6329.1139240505445, 6349.619022858551, 6288.434040936155, 6316.3367846377305, 6319.399657032574, 6324.272708638566, 6348.619082855043, 5235.502627250406, 6241.095041218907, 6334.271558770801, 6338.809835704937, 6357.205349331333, 6055.818325450244, 6124.838682352372, 6140.293866205444, 6151.384861513813, 6167.290761562478, 5319.361676598833, 6178.876422471615, 6222.222222222223, 6272.717727702173, 6280.591761535486, 6104.755809767737, 6113.14415981754, 6117.235345581802, 6135.233095863017, 6147.538934580014, 5924.081767326072, 6030.7889223876955, 6040.335563088145, 6110.388961103854, 6126.24286675468, 5854.853628659318, 5872.236609240771, 5906.497947674496, 5913.615614985184, 5954.7320370582565, 5969.70151492415, 6101.938980610137, 6111.7249723763425, 6114.785982490592, 6118.20463339763, 5730.770769169187, 5876.236089308362, 5925.614835035709, 5957.49361304294, 5961.52307815365], "unit": "score", "name": "dojo.html", "value": 5668.683632755004}, {"lowerIsBetter": false, "replicates": [489.6474538332399, 539.4659287305567, 569.7236840132535, 631.318176369521, 638.1289539778202, 38142.80928595357, 38475.80762096189, 38560.421593676045, 38632.42051369232, 38634.84095477137, 41595.16809663805, 41975.0, 42031.78984105083, 42050.789746051305, 42087.57912420875, 9470.958194598606, 9680.177184939288, 9703.466309352983, 9705.078017588354, 9719.396299610557, 9556.665516706917, 9706.854397184048, 9741.0, 9828.901710982906, 9830.016998300149, 9797.90202097977, 9890.554925028355, 9890.802183956317, 9892.40645561265, 9895.0, 9777.706668799947, 9847.50762461874, 9864.802703945881, 9865.852012219822, 9875.30872838898, 9660.71017869465, 9738.464384458852, 9767.609295628166, 9772.26707996896, 9782.413055216639, 34225.288735563125, 36727.81636091829, 38504.22991540182, 38942.80528597339, 38970.61029389726, 2911.3449473868423, 3162.5572419861237, 3227.483602623569, 3228.542097510899, 3239.7408207343356, 27660.723392766016, 28521.0, 30237.0, 30274.54588181179, 30277.545836812464, 2658.830114749504, 2709.6612923384578, 2829.137113180479, 2835.277004363874, 2839.673437554687, 430872.0, 431212.0, 432738.0, 433697.0, 434696.0, 61899.69050154721, 62781.37218627755, 63029.0, 63103.6844815773, 63189.05216421757, 67388.0, 67404.0, 67538.0, 67655.0, 67715.0, 16079.437219697254, 16476.176191190392, 16754.497365079067, 16817.495475135765, 16826.0, 12770.169938953939, 12796.488140474463, 12844.229346239255, 12866.614001579968, 12877.098603097693, 19700.70448943267, 20879.582408352053, 20971.8951405242, 20977.580448390945, 20978.160873565193, 7392.334689877829, 8592.0, 8815.59429350801, 8844.955775221082, 8945.865812012826, 984.8621193032981, 1010.1060561403065, 1061.8354155105972, 1081.518724167738, 1093.8796732359592, 24579.754202458105, 27447.725522744917, 28599.28501787472, 29228.26929326784, 29460.852695736383, 1092.7432053467428, 1111.7276267314398, 1144.713821544614, 1167.0313639679046, 1248.1886773597234, 11913.16607837443, 14518.419263229353, 14554.417823287162, 14694.706105877822, 14975.0, 530369.0, 538251.0, 543514.0, 545255.0, 545910.0, 55855.603609910075, 56239.0, 56349.43650563442, 56418.7179064102, 56532.43467565354, 1321.7951217561294, 1445.1401416157369, 1450.4560789703862, 1461.8318893327403, 1474.6165996840898, 27936.580951286152, 27966.580501292905, 28525.572116418687, 28728.281792955342, 28902.855485722437, 43643.78178109216, 44082.77958610187, 44107.338389923534, 44255.77872110619, 44268.33597495976, 252012.73993629916, 329156.3542182369, 329323.0, 329593.0, 329972.0, 27771.722282776915, 32315.676843231267, 32460.188495287806, 32750.0, 32848.671513284564, 40092.79721608356, 40255.798721006206, 41738.58261417347, 41930.790346048074, 42011.0, 26599.468010640066, 32453.026409207763, 33801.98594042183, 34226.657733423344, 34870.65129348674, 28517.857410713645, 29184.562231566968, 29510.0, 29587.704122959356, 29944.101676949726, 34544.82727586346, 35094.12264693403, 35410.0, 35645.64354356527, 35650.28699426049, 24707.38231544154, 26879.596806048317, 31166.844165779024, 31186.0, 31188.844055780482, 23472.53054938926, 23644.0, 23684.026354465066, 23761.16835910735, 24170.516589667757], "unit": "score", "name": "ext.html", "value": 19114.753470729873}, {"lowerIsBetter": false, "replicates": [335.98699919741983, 351.6588908758505, 356.5471850749547, 362.851230995292, 364.49153430963787, 20013.599728005433, 20393.796062039375, 21459.57080858382, 22113.889430552867, 22120.668189977125, 12007.639770806889, 12300.5694800682, 12303.32331721755, 12324.507019719224, 12336.383180840967, 5293.04725149473, 5383.488568585978, 5511.255980442629, 6126.254017604983, 6146.231721034871, 6086.208792856923, 6105.908411373833, 6132.080187971796, 6138.232720909887, 6148.784792532262, 6126.571140020201, 6333.714982825761, 6384.07430922516, 6578.407943285103, 6630.966845165767, 6455.128557644704, 6539.803805885807, 6548.607083574977, 6561.671916404209, 6564.868702625945, 5534.114541673353, 5753.7123143843055, 5754.309482862083, 5761.884762304772, 5773.9422605773825, 13111.934440327737, 13132.934335328262, 13140.080194386397, 13325.60023199296, 13502.0, 1993.362124120282, 2006.197520991615, 2084.47888027993, 2229.442639340165, 2698.6491756071587, 9579.568919398644, 9739.805203895954, 9775.364601300964, 9896.95051524745, 10099.394036357842, 1679.9328026878911, 1706.2663054886393, 1711.3496871188868, 1777.3423833181805, 1800.4328636479468, 67594.66202669004, 102841.45737813938, 103361.48319258356, 105857.88492208284, 108310.37534437068, 8447.944006999125, 10840.078593319657, 10870.021698047214, 10895.346279223113, 11036.558537658404, 13669.589912302647, 13699.0, 13703.520376786964, 13706.52027179044, 13719.794203086962, 8128.9593552031865, 8278.220182660636, 8296.668133274723, 8393.706220282262, 8408.075111737704, 8320.625571849281, 8323.042850072197, 8361.03848057469, 8439.367047471464, 8449.0, 4329.112531930947, 4340.587644173798, 4342.913141737147, 4352.673549483801, 4393.626541743987, 1756.7804024496938, 1802.2520653928473, 1803.8556915446732, 1805.8645601579933, 1808.3128411203922, 919.8988111307751, 946.4936259101348, 961.8653388525612, 978.5438549241455, 994.4679596415883, 17239.56901077483, 17322.913385433243, 19023.714644280346, 19536.21855125779, 19639.509012274808, 883.650957871637, 933.9719808405758, 950.6482601437513, 954.0602506531061, 956.258899353007, 9746.463944483121, 9820.705378838646, 10531.0, 10703.44028449273, 10724.41015744126, 467910.0, 527302.0, 601945.0, 614549.0, 618270.0, 72243.63878180576, 92419.53790231005, 113757.0, 114999.47549041673, 120979.79020209685, 845.4377838737171, 911.40758506971, 960.4765402855461, 997.3317877022413, 998.8301988661945, 17137.22882470267, 17389.91305043467, 17411.216495258, 17816.732749008515, 17922.91038544799, 40448.595514044486, 40493.797531012155, 44844.10311793811, 44962.0, 47211.34760283374, 63836.68081659562, 65066.0, 65213.369665758735, 68324.97512537416, 69198.0, 7183.138023437012, 7401.963725078601, 7440.55356678601, 7643.694252229849, 7936.365090792839, 11435.256708313933, 12134.939325303318, 12229.388530573613, 12474.875251247371, 12502.374881255719, 8979.024614584781, 10531.05220530156, 10685.102453390384, 10838.403887785931, 10851.294665846695, 10328.741781455223, 10377.481125943521, 10391.428471434143, 10718.732031698957, 10735.141188705042, 10516.0, 10551.103156231806, 10729.141668666643, 10988.285761425483, 11177.16171287173, 7534.924650753423, 7683.692652293846, 7781.805454863674, 7823.2567906048835, 8044.919550804651, 10271.332363396356, 10784.89215107839, 10879.891201088205, 11165.274257173258, 11178.27341222818], "unit": "score", "name": "jquery.html", "value": 8856.494685352282}, {"lowerIsBetter": false, "replicates": [1564.9139297338645, 1594.8484893935076, 1865.1513561329596, 1936.138418403809, 1994.0727561683814, 8597.699080532182, 8647.0, 8781.121887811216, 8785.516796576187, 8793.472391656496, 7761.107472640634, 7888.96055519723, 8480.66077356905, 8570.228679418851, 8654.134586541342, 2430.368104292888, 2462.581361168606, 2464.0267094497663, 2467.4571594249237, 2471.864047477388, 2189.7372315322104, 2443.1693224303735, 2530.063876365747, 2542.8220024598286, 2552.8468291902454, 2167.2522979572022, 2404.95190096198, 2448.951020979588, 2453.460238747473, 2457.8279520433575, 1996.620642078015, 2412.7587241275905, 2416.673749043883, 2418.9274321770376, 2421.225207933462, 2404.4349577849193, 2406.927792166238, 2410.9035638574437, 2411.2645643078854, 2411.819113566472, 8542.658293668246, 8597.18326758957, 8615.0, 8622.913770862273, 8764.474131552064, 5243.973780131113, 5395.595330350239, 5420.83737487876, 5431.2396264522995, 5435.646682965595, 3444.621091679913, 3449.931001379958, 3621.9094522636883, 3622.6920711739535, 3629.0, 1045.6444808765057, 1048.1457612046158, 1071.276888100529, 1072.2708558180434, 1075.0539525217837, 11676.299422034705, 11884.10869184806, 11887.465064072137, 11894.048476121889, 11959.94020029903, 4789.544993225638, 4903.411590609078, 4979.6265280104135, 4980.228064649913, 4989.426215985136, 10212.029857163558, 10285.74285642865, 10296.22778291631, 10305.84541231882, 10311.74220644475, 5524.226608274845, 5534.667919924817, 5537.1140617501815, 5545.085060964976, 5548.250986116904, 4950.381202349707, 4975.353204083518, 4980.352554167935, 5014.373203349582, 5015.122353588107, 3750.4186851038135, 3766.322062028806, 3769.4157405602177, 3825.6365645264214, 3838.351537439158, 1034.849946757716, 1180.698921774959, 1193.5106606291379, 1237.5359240284893, 1289.9419526121346, 1006.5269323417982, 1106.9391183484986, 1167.1888037813637, 1186.2704436771323, 1200.1418985425462, 7848.175941526058, 7853.0, 7942.967414236113, 7956.84086318282, 7962.124166341698, 613.7452957022875, 636.1920361141417, 639.2201514152727, 641.3746597067915, 777.3159619534773, 6150.630962142286, 6210.130581718563, 6244.219472565929, 6247.656378899204, 6268.937310626927, 10017.549210285555, 10398.324108932895, 10498.10766084876, 10508.316959397614, 10508.632197873038, 10012.799744005079, 10356.792864142675, 10367.688969330931, 10372.377657340583, 10389.013043760831, 8014.679412823539, 8153.3477321812925, 8190.631421586043, 8209.220124088202, 8212.58937053145, 7661.927330173891, 7875.684972601031, 7963.721269755531, 7981.441299109007, 8004.959975200087, 9255.223432969948, 9342.579583918876, 9406.623735050522, 9441.386309889835, 9607.087326703951, 9382.906170938477, 9688.0, 9763.414195148314, 9792.85310720354, 9832.852507212541, 7377.926220737724, 7415.184329723618, 7503.174650788518, 7521.0, 7529.0, 7998.3201427879285, 8123.984501937258, 8125.553094579855, 8235.670573177005, 8260.628271727666, 7197.820054498679, 7311.305425984523, 7339.339459448462, 7376.077990251219, 7470.589117598584, 5956.374580668968, 7140.285971402818, 7208.855822883618, 7281.417486600953, 7414.888776668462, 6056.606320589148, 7633.0458692663415, 7637.350825179699, 7639.159692433939, 7642.885356719765, 6777.356151165632, 6923.89614155778, 6936.861262774615, 6937.0, 6958.756443524452, 6919.135108111486, 7064.187618423742, 7083.929160708533, 7085.185203701641, 7091.6099614519635], "unit": "score", "name": "mootools.html", "value": 4807.978816073962}, {"lowerIsBetter": false, "replicates": [316.79408384550044, 320.97753157278987, 321.45031995288065, 328.9210589458529, 329.46790932643785, 6292.685365731712, 6381.0, 6447.677616119198, 6509.8698026039565, 6511.544191906569, 5395.001924643943, 6389.265234498034, 6444.677766111699, 6467.353264673542, 6472.7087281072345, 4525.570070843265, 4596.586307232356, 4617.930731039037, 4639.420072490939, 4779.402574678165, 4592.392662568108, 4663.673542852001, 4679.438467383919, 4684.46128695201, 4712.3873896393425, 4648.65135114866, 4651.581357677808, 4665.370175026362, 4686.601638860701, 4687.0, 3747.7257732371127, 4617.515160908123, 4675.555822196886, 4676.672632915697, 4702.388689470347, 3312.9006129816144, 3909.9413508797393, 3990.2418540477506, 4003.0, 4020.718549701522, 6095.756169753205, 6121.785737499166, 6124.142620033199, 6141.048137538689, 6198.256209254873, 5564.940971840445, 5589.105743081088, 5589.608727389084, 5596.356419011784, 5606.439356064361, 6818.897716534256, 6847.17833859935, 6854.794356169273, 6857.417119544844, 6872.244053154149, 4596.057808149355, 4608.216603177468, 4610.124076425468, 4620.283856002325, 4621.861344159647, 6975.3024697530345, 7258.891116633255, 7261.583991121734, 7264.4914855960105, 7296.343329100407, 4461.174682683714, 4527.592516673515, 4541.8183272669385, 4544.545545445429, 4544.818207271738, 4630.976845115752, 4631.722096674211, 4632.073585282957, 4634.443866736014, 4636.072785442925, 4416.933745993812, 5466.671999680032, 5469.781208751605, 5471.50756431923, 5478.233047373371, 6688.130543029375, 6907.516473846883, 6928.411085057725, 6928.861422771516, 6930.549514281555, 1555.4322672224782, 1596.3135851583932, 1601.5355546891271, 1601.591594143486, 1607.3249235321193, 978.9510524473748, 1010.56545685356, 1024.882138554076, 1029.341221618165, 1034.9689509314733, 6268.686565671698, 6270.122182894398, 6275.6862156891975, 6281.183446152062, 6285.842853928689, 6853.074834897225, 7005.369516743517, 7068.222495525488, 7140.714371425189, 7261.346478816932, 6333.176687030748, 6334.1765570475545, 6343.682815859189, 6344.175257216532, 6351.364863513611, 3749.343864823645, 3799.1072098056934, 3802.5627052888717, 3808.352580061396, 3810.161764411825, 6182.948898687234, 7455.478116531899, 7459.813504662427, 7462.96268518654, 7485.513441626277, 6877.518573699893, 7081.610511421818, 7369.520981136317, 7370.373518250902, 7372.963135184397, 7354.852902941911, 7392.0, 7401.851962960711, 7411.703531858665, 7414.925850741639, 7112.715491380287, 7152.6066066364765, 7161.176464706626, 7166.570005799668, 7181.569105853666, 5692.886142277049, 7014.964925175342, 7034.648267586498, 7057.894131587927, 7071.82320441993, 6630.5027122965, 7073.0674372836165, 7409.888851667337, 7448.5158464699625, 7471.476996610185, 4177.851090949892, 4540.091981603627, 4575.290829921368, 4584.747838868894, 4597.724136551684, 6890.517663763489, 7174.533655312388, 7176.995220669006, 7178.0, 7178.066851309296, 6927.7228910843005, 6939.097917270722, 6939.410150137295, 6940.826479338057, 6942.51402401827, 6648.501362397937, 6927.134108236471, 6931.68807403658, 6965.547239429421, 6974.4769142315545, 6750.493712971449, 6791.0832037676255, 6806.591604503746, 6808.523403361717, 6814.93185068143, 6964.025036494996, 6976.127984002, 6977.79066628002, 6979.895301570582, 6983.9650801745665, 5224.895502089861, 6693.761654094008, 6739.393454589111, 6798.32016798336, 6824.590524568542], "unit": "score", "name": "prototype.html", "value": 5000.037601991843}, {"lowerIsBetter": false, "replicates": [650.5868773328936, 677.976270830521, 703.789482090804, 732.8387754693969, 758.7913323835946, 15153.166575838324, 15417.69164616707, 15423.537293881187, 15429.459968901077, 15429.919905606612, 15573.065616063044, 15943.601409964756, 15974.0, 15978.920105399486, 15982.760258596101, 731.7365748330596, 898.7887846356117, 910.3400034974657, 919.3472634429563, 920.5443305563755, 735.7682330066039, 901.24745837226, 916.1251005289932, 916.8670542771295, 917.536643994785, 943.1652987106391, 943.3915124744535, 946.8153710026547, 946.9715908522755, 949.9835176361296, 755.029786724057, 940.6331530703031, 942.2509105261367, 942.4910548303908, 945.2390825385562, 711.7580022792224, 712.2094475132599, 714.6998260730454, 715.5456285258898, 715.9355657990806, 15338.233088345538, 15413.614659633487, 15415.845841541553, 15435.768463473058, 15437.0737755735, 12185.32980686062, 12217.389130543437, 12390.690232744164, 12483.687907802378, 12484.50061997519, 9182.632694692204, 9296.163345298955, 9412.62349506019, 9635.325527213097, 9811.411315321033, 3414.607320158189, 3465.2549701814232, 3489.110276879405, 3507.2459421224557, 3728.086618778417, 75755.0, 76472.61763691202, 76886.0, 76886.61556692237, 80793.59603202005, 9338.859917101248, 9419.811603768023, 9431.858522122173, 9452.338336316392, 10026.498675066217, 20312.28906988278, 20510.589788204452, 20512.58974820525, 20526.0, 20530.897345513178, 6413.358664133642, 7380.667869945865, 7402.888956665654, 7763.262490063435, 7807.180246074194, 4052.169305292408, 4090.9590904091174, 4121.258173528794, 4123.835046598162, 4132.33882578786, 3557.5197348358156, 3579.785212887235, 3593.1735700788963, 3599.082234030358, 3599.4420864766003, 624.7625902157242, 633.224300232221, 643.3148696638066, 648.1929997153479, 653.7744478155029, 452.5316297632001, 590.4479311843354, 597.9342272350038, 604.6130476495047, 625.3995470561114, 7555.962220188864, 7754.418418618513, 7840.864233926787, 8010.639521221559, 8408.915910840813, 484.6776893365872, 545.8689914420511, 588.2705445247861, 618.0, 624.1012941364421, 3690.372636651776, 3849.634284742945, 3856.1709232515145, 3871.3225185592974, 3916.4712763776524, 63667.0, 82491.60916012405, 86114.56942715247, 87980.12019879719, 88047.0, 76059.23940760522, 76213.0, 76378.0, 76975.0, 77314.0, 66416.0, 68543.65728171327, 68924.96612550816, 69595.0, 69763.95354069694, 6323.798478289111, 7746.767596972099, 7777.494462859896, 7852.646630901509, 7971.80070498219, 46569.76715116403, 47975.76012119917, 48815.77960551015, 48856.267155991976, 49159.262611061575, 63700.36299636944, 67149.0, 67256.0, 67411.66294168498, 68190.3180968184, 8212.824825581822, 10128.20064624704, 10210.081092701692, 10277.331973421704, 10418.0, 9109.90890091117, 10819.945900270448, 11058.723531911766, 11308.038816700673, 11493.712657183638, 10377.0, 10423.53094110782, 10529.526171322155, 10694.0, 11072.335659860435, 9920.0, 10350.637727679496, 10458.215633827645, 10593.311434756715, 11424.48589813477, 10290.948545257226, 10314.43270620123, 10670.519826607666, 10904.836427453436, 10960.958708922639, 8605.268552173135, 8638.13618638131, 8740.344474164336, 8840.071792461957, 8913.0, 8718.298567366624, 8893.866592000995, 8952.641894324415, 9101.180893719596, 9165.908340916505], "unit": "score", "name": "yui.html", "value": 6703.577577250583}], "name": "dromaeo_css", "value": 7326.529776433959}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 108.0, 112.0, 112.0, 112.0, 112.0, 115.0, 111.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.5}, {"lowerIsBetter": true, "replicates": [161.0, 127.0, 145.0, 163.0, 119.0, 146.0, 149.0, 145.0, 164.0, 144.0], "unit": "score", "name": "audio-beat-detection", "value": 146.3}, {"lowerIsBetter": true, "replicates": [187.0, 183.0, 186.0, 187.0, 187.0, 186.0, 185.0, 185.0, 186.0, 185.0], "unit": "score", "name": "audio-dft", "value": 185.7}, {"lowerIsBetter": true, "replicates": [79.0, 75.0, 86.0, 82.0, 60.0, 58.0, 61.0, 81.0, 79.0, 55.0], "unit": "score", "name": "audio-fft", "value": 71.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 130.0, 78.0, 77.0, 78.0, 122.0, 80.0, 79.0, 124.0], "unit": "score", "name": "audio-oscillator", "value": 96.0}, {"lowerIsBetter": true, "replicates": [92.0, 90.0, 89.0, 89.0, 94.0, 87.0, 94.0, 85.0, 94.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 89.8}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 92.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 93.1}, {"lowerIsBetter": true, "replicates": [86.0, 86.0, 86.0, 88.0, 88.0, 86.0, 85.0, 89.0, 88.0, 94.0], "unit": "score", "name": "imaging-desaturate", "value": 87.6}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 55.0, 53.0, 54.0, 54.0, 53.0, 65.0, 54.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 55.0}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 76.0, 77.0, 73.0, 70.0, 71.0, 70.0, 70.0, 71.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 70.7}, {"lowerIsBetter": true, "replicates": [82.0, 84.0, 84.0, 86.0, 83.0, 84.0, 83.0, 83.0, 83.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.8}, {"lowerIsBetter": true, "replicates": [123.0, 138.0, 131.0, 126.0, 144.0, 145.0, 132.0, 123.0, 122.0, 134.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 131.8}, {"lowerIsBetter": true, "replicates": [143.0, 131.0, 141.0, 139.0, 162.0, 129.0, 140.0, 139.0, 138.0, 137.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 139.9}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 68.0, 97.0, 88.0, 88.0, 94.0, 97.0, 95.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 82.7}], "name": "kraken", "value": 1445.5000000000002}]} 09:50:09 INFO - Return code: 0 09:50:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:50:09 ERROR - # TBPL SUCCESS # 09:50:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:50:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:50:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:50:09 INFO - 2016-04-07 09:50:09.297 screenresolution[2634:71008] starting screenresolution argv=screenresolution get 09:50:09 INFO - 2016-04-07 09:50:09.299 screenresolution[2634:71008] Display 0: 1600x1200x32@60 09:50:09 INFO - 2016-04-07 09:50:09.307 screenresolution[2635:71011] starting screenresolution argv=screenresolution list 09:50:09 INFO - Available Modes on Display 0 09:50:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:50:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:50:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:50:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:50:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:50:09 INFO - Intel Iris: 09:50:09 INFO - Chipset Model: Intel Iris 09:50:09 INFO - Type: GPU 09:50:09 INFO - Bus: Built-In 09:50:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:50:09 INFO - Vendor: Intel (0x8086) 09:50:09 INFO - Device ID: 0x0a2e 09:50:09 INFO - Revision ID: 0x0009 09:50:09 INFO - Displays: 09:50:09 INFO - Display: 09:50:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:50:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:50:09 INFO - Display Serial Number: 1600x1200 60 09:50:09 INFO - Main Display: Yes 09:50:09 INFO - Mirror: Off 09:50:09 INFO - Online: Yes 09:50:09 INFO - Rotation: Supported 09:50:09 INFO - Return code: 0 09:50:09 INFO - Running post-action listener: _resource_record_post_action 09:50:09 INFO - Running post-run listener: _resource_record_post_run 09:50:10 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 32768; Write bytes: 692232704; Read time: 2; Write time: 2001 09:50:10 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 373293568; Write bytes: 367658496; Read time: 28039; Write time: 704 09:50:10 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 4096; Write bytes: 296134144; Read time: 1; Write time: 1232 09:50:10 INFO - Running post-run listener: _upload_blobber_files 09:50:10 INFO - Blob upload gear active. 09:50:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:50:10 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:50:10 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:50:10 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:50:11 INFO - (blobuploader) - INFO - Open directory for files ... 09:50:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:50:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:50:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:11 INFO - (blobuploader) - INFO - Done attempting. 09:50:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:50:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:50:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:12 INFO - (blobuploader) - INFO - Done attempting. 09:50:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:50:12 INFO - Return code: 0 09:50:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:50:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:50:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5f1415d4e744e40448b7d1d318041adbee10887435b0cb9ab6b4c0fd6a1bc6480219e88c9f0751807c19b2b48e0c42235f6af57139459a95d63cad39d32e96df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c54827333a681b4aa4a30edefec0837d1565957e21fba430ea1013526e9dca37b0bed960f5787c2fb7b566b75c29e6ad6ce7a5edea5d838bcbf4b12b2e5b2e4d"} 09:50:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:50:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:50:12 INFO - Contents: 09:50:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5f1415d4e744e40448b7d1d318041adbee10887435b0cb9ab6b4c0fd6a1bc6480219e88c9f0751807c19b2b48e0c42235f6af57139459a95d63cad39d32e96df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c54827333a681b4aa4a30edefec0837d1565957e21fba430ea1013526e9dca37b0bed960f5787c2fb7b566b75c29e6ad6ce7a5edea5d838bcbf4b12b2e5b2e4d"} 09:50:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:50:12 INFO - Copying logs to upload dir... 09:50:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:50:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.923015 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-07 09:50:13.283496) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 09:50:13.288963) ========= 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.ttRJV3XOs2/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.vabcG1vsSc/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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/5f1415d4e744e40448b7d1d318041adbee10887435b0cb9ab6b4c0fd6a1bc6480219e88c9f0751807c19b2b48e0c42235f6af57139459a95d63cad39d32e96df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c54827333a681b4aa4a30edefec0837d1565957e21fba430ea1013526e9dca37b0bed960f5787c2fb7b566b75c29e6ad6ce7a5edea5d838bcbf4b12b2e5b2e4d"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011211 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5f1415d4e744e40448b7d1d318041adbee10887435b0cb9ab6b4c0fd6a1bc6480219e88c9f0751807c19b2b48e0c42235f6af57139459a95d63cad39d32e96df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c54827333a681b4aa4a30edefec0837d1565957e21fba430ea1013526e9dca37b0bed960f5787c2fb7b566b75c29e6ad6ce7a5edea5d838bcbf4b12b2e5b2e4d"}' ========= master_lag: 0.50 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 09:50:13.801004) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 8 secs) (at 2016-04-07 09:50:13.801376) ========= 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.ttRJV3XOs2/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.vabcG1vsSc/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005570 ========= master_lag: 8.46 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 8 secs) (at 2016-04-07 09:50:22.269175) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:50:22.269484) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:50:22.284627) ========= ========= Total master_lag: 9.48 =========