builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0028 starttime: 1460046579.66 results: success (0) buildid: 20160407091416 builduid: 563c124fa3ca4f7f925fa567d1beeac2 revision: 1725b460c3e0de97590cb8764df9ace9115b813e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:39.662877) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:39.663273) ========= ========= Started set props: basedir (results: 0, elapsed: 2 secs) (at 2016-04-07 09:29:39.663564) ========= 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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.005973 basedir: '/builds/slave/test' ========= master_lag: 2.13 ========= ========= Finished set props: basedir (results: 0, elapsed: 2 secs) (at 2016-04-07 09:29:41.804376) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 15 secs) (at 2016-04-07 09:29:41.804694) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 15 secs) (at 2016-04-07 09:29:57.436265) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-04-07 09:29:57.436520) ========= 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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.006120 ========= master_lag: 10.35 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-04-07 09:30:07.795159) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:30:07.795474) ========= 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:30:07.795839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2016-04-07 09:30:07.796120) ========= 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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:30:09-- 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% 8.42M=0.001s 2016-04-07 09:30:09 (8.42 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127533 ========= master_lag: 12.61 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2016-04-07 09:30:20.532129) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-04-07 09:30:20.532442) ========= 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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.020897 ========= master_lag: 9.24 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2016-04-07 09:30:29.796274) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 16 secs) (at 2016-04-07 09:30:29.796617) ========= 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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:30:31,396 truncating revision to first 12 chars 2016-04-07 09:30:31,396 Setting DEBUG logging. 2016-04-07 09:30:31,396 attempt 1/10 2016-04-07 09:30:31,396 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:30:32,155 unpacking tar archive at: fx-team-1725b460c3e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.007837 ========= master_lag: 15.63 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 16 secs) (at 2016-04-07 09:30:46.437846) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 7 secs) (at 2016-04-07 09:30:46.438148) ========= 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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.005321 script_repo_revision: '1725b460c3e0de97590cb8764df9ace9115b813e' ========= master_lag: 7.92 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 7 secs) (at 2016-04-07 09:30:54.364342) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 29 secs) (at 2016-04-07 09:30:54.364633) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 29 secs) (at 2016-04-07 09:31:23.963724) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-07 09:31:23.964022) ========= /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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:31:24 INFO - MultiFileLogger online at 20160407 09:31:24 in /builds/slave/test 09:31:24 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:31:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:31:24 INFO - {'append_to_log': False, 09:31:24 INFO - 'base_work_dir': '/builds/slave/test', 09:31:24 INFO - 'blob_upload_branch': 'Fx-Team', 09:31:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:31:24 INFO - 'branch': 'Fx-Team', 09:31:24 INFO - 'buildbot_json_path': 'buildprops.json', 09:31:24 INFO - 'config_files': ('talos/mac_config.py',), 09:31:24 INFO - 'default_actions': ('clobber', 09:31:24 INFO - 'read-buildbot-config', 09:31:24 INFO - 'download-and-extract', 09:31:24 INFO - 'populate-webroot', 09:31:24 INFO - 'create-virtualenv', 09:31:24 INFO - 'install', 09:31:24 INFO - 'run-tests'), 09:31:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:31:24 INFO - 'download_minidump_stackwalk': True, 09:31:24 INFO - 'download_symbols': 'ondemand', 09:31:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:31:24 INFO - 'tooltool.py': '/tools/tooltool.py', 09:31:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:31:24 INFO - '/tools/misc-python/virtualenv.py')}, 09:31:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:31:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:31:24 INFO - 'installer_path': 'installer.exe', 09:31:24 INFO - 'log_level': 'info', 09:31:24 INFO - 'log_name': 'talos', 09:31:24 INFO - 'log_to_console': True, 09:31:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:31:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:31:24 INFO - 'opt_config_files': (), 09:31:24 INFO - 'pip_index': False, 09:31:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:31:24 INFO - 'cmd': ('bash', 09:31:24 INFO - '-c', 09:31:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:31:24 INFO - 'enabled': True, 09:31:24 INFO - 'halt_on_failure': False, 09:31:24 INFO - 'name': 'check_screen_resolution'},), 09:31:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:31:24 INFO - 'cmd': ('bash', 09:31:24 INFO - '-c', 09:31:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:31:24 INFO - 'enabled': True, 09:31:24 INFO - 'halt_on_failure': False, 09:31:24 INFO - 'name': 'check_screen_resolution'},), 09:31:24 INFO - 'run_cmd_checks_enabled': True, 09:31:24 INFO - 'sps_profile': False, 09:31:24 INFO - 'sps_profile_interval': 0, 09:31:24 INFO - 'suite': 'dromaeojs', 09:31:24 INFO - 'system_bits': '32', 09:31:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:31:24 INFO - 'title': 't-yosemite-r7-0028', 09:31:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:31:24 INFO - 'use_talos_json': True, 09:31:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:31:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:31:24 INFO - 'work_dir': 'build'} 09:31:24 INFO - ##### 09:31:24 INFO - ##### Running clobber step. 09:31:24 INFO - ##### 09:31:24 INFO - Running pre-action listener: _resource_record_pre_action 09:31:24 INFO - Running main action method: clobber 09:31:24 INFO - rmtree: /builds/slave/test/build 09:31:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:31:26 INFO - Running post-action listener: _resource_record_post_action 09:31:26 INFO - ##### 09:31:26 INFO - ##### Running read-buildbot-config step. 09:31:26 INFO - ##### 09:31:26 INFO - Running pre-action listener: _resource_record_pre_action 09:31:26 INFO - Running main action method: read_buildbot_config 09:31:26 INFO - Using buildbot properties: 09:31:26 INFO - { 09:31:26 INFO - "project": "", 09:31:26 INFO - "product": "firefox", 09:31:26 INFO - "script_repo_revision": "production", 09:31:26 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:31:26 INFO - "repository": "", 09:31:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:31:26 INFO - "buildid": "20160407091416", 09:31:26 INFO - "stage_platform": "macosx64", 09:31:26 INFO - "basedir": "/builds/slave/test", 09:31:26 INFO - "buildnumber": 686, 09:31:26 INFO - "platform": "yosemite_r7", 09:31:26 INFO - "mozci_request": { 09:31:26 INFO - "priority": -1, 09:31:26 INFO - "type": "trigger_all_talos_jobs", 09:31:26 INFO - "times": 6 09:31:26 INFO - }, 09:31:26 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:31:26 INFO - "slavebuilddir": "test", 09:31:26 INFO - "branch": "fx-team", 09:31:26 INFO - "revision": "1725b460c3e0de97590cb8764df9ace9115b813e", 09:31:26 INFO - "slavename": "t-yosemite-r7-0028", 09:31:26 INFO - "builduid": "563c124fa3ca4f7f925fa567d1beeac2", 09:31:26 INFO - "repo_path": "integration/fx-team" 09:31:26 INFO - } 09:31:26 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg. 09:31:26 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:31:26 INFO - Running post-action listener: _resource_record_post_action 09:31:26 INFO - ##### 09:31:26 INFO - ##### Running download-and-extract step. 09:31:26 INFO - ##### 09:31:26 INFO - Running pre-action listener: _resource_record_pre_action 09:31:26 INFO - Running main action method: download_and_extract 09:31:26 INFO - mkdir: /builds/slave/test/build/tests 09:31: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:31:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json 09:31:26 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:31:26 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:31:26 INFO - Downloaded 1199 bytes. 09:31:26 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:31:26 INFO - Using the following test package requirements: 09:31:26 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:31:26 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:31:26 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:31:26 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:31:26 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:31:26 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:31:26 INFO - u'jsshell-mac.zip'], 09:31:26 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:31:26 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:31:26 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:31:26 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:31:26 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:31:26 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:31:26 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:31:26 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:31:26 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:31:26 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:31:26 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:31:26 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:31: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:31:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip 09:31:26 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:31: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.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:31:26 INFO - Downloaded 17970713 bytes. 09:31:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:31:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:31:30 INFO - Return code: 0 09:31:30 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:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip 09:31:30 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:31:30 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:31:30 INFO - Downloaded 17970713 bytes. 09:31:30 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:31:30 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:31:34 INFO - Return code: 0 09:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.talos.tests.zip 09:31:34 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:31:34 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:31:34 INFO - Downloaded 10847617 bytes. 09:31:34 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:31:34 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:31:34 INFO - Return code: 0 09:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:31:34 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:31:34 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:31:37 INFO - Downloaded 108918030 bytes. 09:31:37 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:31:37 INFO - mkdir: /builds/slave/test/properties 09:31:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:31:37 INFO - Writing to file /builds/slave/test/properties/build_url 09:31:37 INFO - Contents: 09:31:37 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:31:37 INFO - Running post-action listener: _resource_record_post_action 09:31:37 INFO - Running post-action listener: set_extra_try_arguments 09:31:37 INFO - ##### 09:31:37 INFO - ##### Running populate-webroot step. 09:31:37 INFO - ##### 09:31:37 INFO - Running pre-action listener: _resource_record_pre_action 09:31:37 INFO - Running main action method: populate_webroot 09:31:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:31:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:31:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:31:37 INFO - '--tppagecycles', 09:31:37 INFO - '7'], 09:31:37 INFO - 'tests': ['tsvgm']}}, 09:31:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:31:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:31:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:31:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:31:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:37 INFO - 'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:37 INFO - 'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['damp', 'tps']}, 09:31:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:37 INFO - 'tests': ['damp', 'tps']}, 09:31:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['dromaeo_dom']}, 09:31:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:31:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['a11yr', 09:31:37 INFO - 'ts_paint', 09:31:37 INFO - 'tpaint', 09:31:37 INFO - 'sessionrestore', 09:31:37 INFO - 'sessionrestore_no_auto_restore', 09:31:37 INFO - 'tabpaint']}, 09:31:37 INFO - 'other-e10s': {'tests': ['a11yr', 09:31:37 INFO - 'ts_paint', 09:31:37 INFO - 'tpaint', 09:31:37 INFO - 'sessionrestore', 09:31:37 INFO - 'sessionrestore_no_auto_restore', 09:31:37 INFO - 'tabpaint']}, 09:31:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:31:37 INFO - 'ts_paint', 09:31:37 INFO - 'tpaint', 09:31:37 INFO - 'sessionrestore', 09:31:37 INFO - 'sessionrestore_no_auto_restore', 09:31:37 INFO - 'tabpaint']}, 09:31:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:31:37 INFO - 'ts_paint', 09:31:37 INFO - 'tpaint', 09:31:37 INFO - 'sessionrestore', 09:31:37 INFO - 'sessionrestore_no_auto_restore', 09:31:37 INFO - 'tabpaint']}, 09:31:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['a11yr', 09:31:37 INFO - 'ts_paint', 09:31:37 INFO - 'tpaint', 09:31:37 INFO - 'sessionrestore', 09:31:37 INFO - 'sessionrestore_no_auto_restore', 09:31:37 INFO - 'tabpaint']}, 09:31:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['a11yr', 09:31:37 INFO - 'ts_paint', 09:31:37 INFO - 'tpaint', 09:31:37 INFO - 'sessionrestore', 09:31:37 INFO - 'sessionrestore_no_auto_restore', 09:31:37 INFO - 'tabpaint']}, 09:31:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['tsvgx', 09:31:37 INFO - 'tsvgr_opacity', 09:31:37 INFO - 'tart', 09:31:37 INFO - 'tscrollx', 09:31:37 INFO - 'cart']}, 09:31:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:31:37 INFO - 'tsvgr_opacity', 09:31:37 INFO - 'tart', 09:31:37 INFO - 'tscrollx', 09:31:37 INFO - 'cart']}, 09:31:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:37 INFO - 'talos_options': ['--disable-e10s'], 09:31:37 INFO - 'tests': ['tp5o']}, 09:31:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:37 INFO - 'tests': ['tp5o']}, 09:31:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:37 INFO - 'talos_options': ['--disable-e10s', 09:31:37 INFO - '--xperf_path', 09:31:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:37 INFO - 'tests': ['tp5n']}, 09:31:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:37 INFO - 'talos_options': ['--xperf_path', 09:31:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:37 INFO - 'tests': ['tp5n']}}, 09:31:37 INFO - 'talos.zip': {'path': '', 09:31:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:31:37 INFO - Running post-action listener: _resource_record_post_action 09:31:37 INFO - ##### 09:31:37 INFO - ##### Running create-virtualenv step. 09:31:37 INFO - ##### 09:31:37 INFO - Running pre-action listener: _resource_record_pre_action 09:31:37 INFO - Running main action method: create_virtualenv 09:31:37 INFO - Creating virtualenv /builds/slave/test/build/venv 09:31:37 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:31:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:31:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:31:37 INFO - Using real prefix '/tools/python27' 09:31:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:31:38 INFO - Installing distribute.............................................................................................................................................................................................done. 09:31:41 INFO - Installing pip.................done. 09:31:41 INFO - Return code: 0 09:31:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:31:41 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:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:41 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:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:41 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 0x10eadca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaec420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff568d6f730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, '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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:31:41 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:31:41 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:31:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:31:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:41 INFO - 'HOME': '/Users/cltbld', 09:31:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:41 INFO - 'LOGNAME': 'cltbld', 09:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:41 INFO - 'MOZ_NO_REMOTE': '1', 09:31:41 INFO - 'NO_EM_RESTART': '1', 09:31:41 INFO - 'PAGER': '/bin/cat', 09:31:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:41 INFO - 'PWD': '/builds/slave/test', 09:31:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:41 INFO - 'SHELL': '/bin/bash', 09:31:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:31:41 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:31:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:41 INFO - 'USER': 'cltbld', 09:31:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:41 INFO - 'XPC_FLAGS': '0x0', 09:31:41 INFO - 'XPC_SERVICE_NAME': '0', 09:31:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:41 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:31:41 INFO - Cleaning up... 09:31:41 INFO - Return code: 0 09:31:41 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:31:41 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:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:41 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:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:41 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 0x10eadca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaec420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff568d6f730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, '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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:31:41 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:31:41 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:31:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:31:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:41 INFO - 'HOME': '/Users/cltbld', 09:31:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:41 INFO - 'LOGNAME': 'cltbld', 09:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:41 INFO - 'MOZ_NO_REMOTE': '1', 09:31:41 INFO - 'NO_EM_RESTART': '1', 09:31:41 INFO - 'PAGER': '/bin/cat', 09:31:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:41 INFO - 'PWD': '/builds/slave/test', 09:31:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:41 INFO - 'SHELL': '/bin/bash', 09:31:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:31:41 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:31:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:41 INFO - 'USER': 'cltbld', 09:31:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:41 INFO - 'XPC_FLAGS': '0x0', 09:31:41 INFO - 'XPC_SERVICE_NAME': '0', 09:31:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:41 INFO - Downloading/unpacking psutil>=0.7.1 09:31: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:31: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:31: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:31: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:31: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:31: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:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:31:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:45 INFO - Installing collected packages: psutil 09:31:45 INFO - Running setup.py install for psutil 09:31:45 INFO - building 'psutil._psutil_osx' extension 09:31:45 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:31:45 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:31:45 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:31:45 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:31:45 INFO - building 'psutil._psutil_posix' extension 09:31:45 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:31:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:31:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:31:46 INFO - ^ 09:31:46 INFO - 1 warning generated. 09:31:46 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:31:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:46 INFO - Successfully installed psutil 09:31:46 INFO - Cleaning up... 09:31:46 INFO - Return code: 0 09:31:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:31:46 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:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:46 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:31:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:46 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 0x10eadca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaec420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff568d6f730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, '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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:31:46 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:31:46 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:31:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:31:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:46 INFO - 'HOME': '/Users/cltbld', 09:31:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:46 INFO - 'LOGNAME': 'cltbld', 09:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:46 INFO - 'MOZ_NO_REMOTE': '1', 09:31:46 INFO - 'NO_EM_RESTART': '1', 09:31:46 INFO - 'PAGER': '/bin/cat', 09:31:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:46 INFO - 'PWD': '/builds/slave/test', 09:31:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:46 INFO - 'SHELL': '/bin/bash', 09:31:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:31:46 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:31:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:46 INFO - 'USER': 'cltbld', 09:31:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:46 INFO - 'XPC_FLAGS': '0x0', 09:31:46 INFO - 'XPC_SERVICE_NAME': '0', 09:31:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:31:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:31:49 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:31:49 INFO - Installing collected packages: mozsystemmonitor 09:31:49 INFO - Running setup.py install for mozsystemmonitor 09:31:49 INFO - Successfully installed mozsystemmonitor 09:31:49 INFO - Cleaning up... 09:31:49 INFO - Return code: 0 09:31:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10eadca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaec420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff568d6f730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, '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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:49 INFO - 'HOME': '/Users/cltbld', 09:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:49 INFO - 'LOGNAME': 'cltbld', 09:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:49 INFO - 'MOZ_NO_REMOTE': '1', 09:31:49 INFO - 'NO_EM_RESTART': '1', 09:31:49 INFO - 'PAGER': '/bin/cat', 09:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:49 INFO - 'PWD': '/builds/slave/test', 09:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:49 INFO - 'SHELL': '/bin/bash', 09:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:31:49 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:49 INFO - 'USER': 'cltbld', 09:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:49 INFO - 'XPC_FLAGS': '0x0', 09:31:49 INFO - 'XPC_SERVICE_NAME': '0', 09:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:49 INFO - Downloading/unpacking blobuploader==1.2.4 09:31:49 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:31:49 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:31:49 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:31:49 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:31:49 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:31:49 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:31:51 INFO - Downloading blobuploader-1.2.4.tar.gz 09:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:31:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:31: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:31: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:31: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:31: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:31: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:31: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:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:31:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:31: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:31: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:31: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:31: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:31: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:31: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:31:53 INFO - Downloading docopt-0.6.1.tar.gz 09:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:53 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:53 INFO - Running setup.py install for blobuploader 09:31:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:53 INFO - Running setup.py install for requests 09:31:53 INFO - Running setup.py install for docopt 09:31:53 INFO - Successfully installed blobuploader requests docopt 09:31:53 INFO - Cleaning up... 09:31:54 INFO - Return code: 0 09:31:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:54 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 0x10eadca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaec420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff568d6f730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, '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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:31:54 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:31:54 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:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:54 INFO - 'HOME': '/Users/cltbld', 09:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:54 INFO - 'LOGNAME': 'cltbld', 09:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:54 INFO - 'MOZ_NO_REMOTE': '1', 09:31:54 INFO - 'NO_EM_RESTART': '1', 09:31:54 INFO - 'PAGER': '/bin/cat', 09:31:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:54 INFO - 'PWD': '/builds/slave/test', 09:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:54 INFO - 'SHELL': '/bin/bash', 09:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:31:54 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:54 INFO - 'USER': 'cltbld', 09:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:54 INFO - 'XPC_FLAGS': '0x0', 09:31:54 INFO - 'XPC_SERVICE_NAME': '0', 09:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:54 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-9I_DW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:54 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-dCgCTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:54 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-tt_I4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:54 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-XKXrot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:54 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-hoCDzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:54 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-4mtAlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:54 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-GmrGV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-QqcSkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-yElEaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-zqwoD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-pm0w3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-n9gHBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-5B90Oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-TgI83k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-G3pzZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-JTJ07t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-YAc0w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:56 INFO - Running setup.py install for manifestparser 09:31:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:31:56 INFO - Running setup.py install for mozcrash 09:31:56 INFO - Running setup.py install for mozdebug 09:31:56 INFO - Running setup.py install for mozdevice 09:31:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:31:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:31:56 INFO - Running setup.py install for mozfile 09:31:56 INFO - Running setup.py install for mozhttpd 09:31:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:31:57 INFO - Running setup.py install for mozinfo 09:31:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:31:57 INFO - Running setup.py install for mozInstall 09:31:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:31:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:31:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:31:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:31:57 INFO - Running setup.py install for mozleak 09:31:57 INFO - Running setup.py install for mozlog 09:31:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:31:57 INFO - Running setup.py install for moznetwork 09:31:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:31:57 INFO - Running setup.py install for mozprocess 09:31:57 INFO - Running setup.py install for mozprofile 09:31:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Running setup.py install for mozrunner 09:31:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Running setup.py install for mozscreenshot 09:31:58 INFO - Running setup.py install for moztest 09:31:58 INFO - Running setup.py install for mozversion 09:31:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:31:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:31:58 INFO - Cleaning up... 09:31:58 INFO - Return code: 0 09:31:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:58 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 0x10eadca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaec420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff568d6f730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, '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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:31:58 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:31:58 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:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:58 INFO - 'HOME': '/Users/cltbld', 09:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:58 INFO - 'LOGNAME': 'cltbld', 09:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:58 INFO - 'MOZ_NO_REMOTE': '1', 09:31:58 INFO - 'NO_EM_RESTART': '1', 09:31:58 INFO - 'PAGER': '/bin/cat', 09:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:58 INFO - 'PWD': '/builds/slave/test', 09:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:58 INFO - 'SHELL': '/bin/bash', 09:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:31:58 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:58 INFO - 'USER': 'cltbld', 09:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:58 INFO - 'XPC_FLAGS': '0x0', 09:31:58 INFO - 'XPC_SERVICE_NAME': '0', 09:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:58 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-tTnd9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:59 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:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:59 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-zbk6TM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:59 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:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:59 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-eGX0Yd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:59 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:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:59 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-PuXlzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:59 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:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:59 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-vW6dti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:59 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:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:59 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Ok1cKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:59 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:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:59 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-dcRtCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:59 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:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:59 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-xFBr2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:59 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:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:59 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-x6ekmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:59 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:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:59 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-hjOxcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Jqc9LX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-sbWA_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-58pmgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Quupa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-xQpM4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-WeXJES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:00 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:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-2J4o9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 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:32:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:03 INFO - Downloading blessings-1.6.tar.gz 09:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:32:03 INFO - Installing collected packages: blessings 09:32:03 INFO - Running setup.py install for blessings 09:32:03 INFO - Successfully installed blessings 09:32:03 INFO - Cleaning up... 09:32:03 INFO - Return code: 0 09:32:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:32:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:03 INFO - Reading from file tmpfile_stdout 09:32:03 INFO - Current package versions: 09:32:03 INFO - blessings == 1.6 09:32:03 INFO - blobuploader == 1.2.4 09:32:03 INFO - docopt == 0.6.1 09:32:03 INFO - manifestparser == 1.1 09:32:03 INFO - mozInstall == 1.12 09:32:03 INFO - mozcrash == 0.17 09:32:03 INFO - mozdebug == 0.1 09:32:03 INFO - mozdevice == 0.48 09:32:03 INFO - mozfile == 1.2 09:32:03 INFO - mozhttpd == 0.7 09:32:03 INFO - mozinfo == 0.9 09:32:03 INFO - mozleak == 0.1 09:32:03 INFO - mozlog == 3.1 09:32:03 INFO - moznetwork == 0.27 09:32:03 INFO - mozprocess == 0.22 09:32:03 INFO - mozprofile == 0.28 09:32:03 INFO - mozrunner == 6.11 09:32:03 INFO - mozscreenshot == 0.1 09:32:03 INFO - mozsystemmonitor == 0.0 09:32:03 INFO - moztest == 0.7 09:32:03 INFO - mozversion == 1.4 09:32:03 INFO - psutil == 3.1.1 09:32:03 INFO - requests == 1.2.3 09:32:03 INFO - wsgiref == 0.1.2 09:32:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:03 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:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:03 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:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:03 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 0x10eadca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaec420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff568d6f730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, '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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:32:03 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:32:03 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:32:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:32:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:03 INFO - 'HOME': '/Users/cltbld', 09:32:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:03 INFO - 'LOGNAME': 'cltbld', 09:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:03 INFO - 'MOZ_NO_REMOTE': '1', 09:32:03 INFO - 'NO_EM_RESTART': '1', 09:32:03 INFO - 'PAGER': '/bin/cat', 09:32:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:03 INFO - 'PWD': '/builds/slave/test', 09:32:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:03 INFO - 'SHELL': '/bin/bash', 09:32:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:32:03 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:32:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:03 INFO - 'USER': 'cltbld', 09:32:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:03 INFO - 'XPC_FLAGS': '0x0', 09:32:03 INFO - 'XPC_SERVICE_NAME': '0', 09:32:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 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:32:04 INFO - Cleaning up... 09:32:04 INFO - Return code: 0 09:32:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:32:04 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:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:04 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:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:04 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 0x10eadca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaec420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff568d6f730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, '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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:32:04 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:32:04 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:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:04 INFO - 'HOME': '/Users/cltbld', 09:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:04 INFO - 'LOGNAME': 'cltbld', 09:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:04 INFO - 'MOZ_NO_REMOTE': '1', 09:32:04 INFO - 'NO_EM_RESTART': '1', 09:32:04 INFO - 'PAGER': '/bin/cat', 09:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:04 INFO - 'PWD': '/builds/slave/test', 09:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:04 INFO - 'SHELL': '/bin/bash', 09:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:32:04 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:04 INFO - 'USER': 'cltbld', 09:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:04 INFO - 'XPC_FLAGS': '0x0', 09:32:04 INFO - 'XPC_SERVICE_NAME': '0', 09:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:04 INFO - Downloading/unpacking jsonschema 09:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:32:06 INFO - Downloading/unpacking functools32 (from jsonschema) 09:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:07 INFO - Downloading functools32-3.2.3-2.tar.gz 09:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:32:07 INFO - warning: no files found matching '*.txt' 09:32:07 INFO - no previously-included directories found matching 'build' 09:32:07 INFO - no previously-included directories found matching 'dist' 09:32:07 INFO - no previously-included directories found matching '.git*' 09:32:07 INFO - Installing collected packages: jsonschema, functools32 09:32:07 INFO - Running setup.py install for functools32 09:32:07 INFO - warning: no files found matching '*.txt' 09:32:07 INFO - no previously-included directories found matching 'build' 09:32:07 INFO - no previously-included directories found matching 'dist' 09:32:07 INFO - no previously-included directories found matching '.git*' 09:32:07 INFO - Successfully installed jsonschema functools32 09:32:07 INFO - Cleaning up... 09:32:07 INFO - Return code: 0 09:32:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:32:07 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:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:07 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:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:07 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 0x10eadca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaec420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff568d6f730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, '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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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:32:07 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:32:07 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:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:07 INFO - 'HOME': '/Users/cltbld', 09:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:07 INFO - 'LOGNAME': 'cltbld', 09:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:07 INFO - 'MOZ_NO_REMOTE': '1', 09:32:07 INFO - 'NO_EM_RESTART': '1', 09:32:07 INFO - 'PAGER': '/bin/cat', 09:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:07 INFO - 'PWD': '/builds/slave/test', 09:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:07 INFO - 'SHELL': '/bin/bash', 09:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:32:07 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:07 INFO - 'USER': 'cltbld', 09:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:07 INFO - 'XPC_FLAGS': '0x0', 09:32:07 INFO - 'XPC_SERVICE_NAME': '0', 09:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:07 INFO - Downloading/unpacking flake8 09:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:07 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:32:07 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:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:32:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:32:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:32:11 INFO - no previously-included directories found matching 'docs/_build' 09:32:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:32:11 INFO - Running setup.py install for pep8 09:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:32:11 INFO - no previously-included directories found matching 'docs/_build' 09:32:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:32:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:32:11 INFO - Cleaning up... 09:32:11 INFO - Return code: 0 09:32:11 INFO - Running post-action listener: _resource_record_post_action 09:32:11 INFO - Running post-action listener: _start_resource_monitoring 09:32:11 INFO - Starting resource monitoring. 09:32:11 INFO - ##### 09:32:11 INFO - ##### Running install step. 09:32:11 INFO - ##### 09:32:11 INFO - Running pre-action listener: _resource_record_pre_action 09:32:11 INFO - Running main action method: install 09:32:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:12 INFO - Reading from file tmpfile_stdout 09:32:12 INFO - Detecting whether we're running mozinstall >=1.0... 09:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:32:12 INFO - Reading from file tmpfile_stdout 09:32:12 INFO - Output received: 09:32:12 INFO - Usage: mozinstall [options] installer 09:32:12 INFO - Options: 09:32:12 INFO - -h, --help show this help message and exit 09:32:12 INFO - -d DEST, --destination=DEST 09:32:12 INFO - Directory to install application into. [default: 09:32:12 INFO - "/builds/slave/test"] 09:32:12 INFO - --app=APP Application being installed. [default: firefox] 09:32:12 INFO - mkdir: /builds/slave/test/build/application 09:32:12 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:32:12 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:32:45 INFO - Reading from file tmpfile_stdout 09:32:45 INFO - Output received: 09:32:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:32:45 INFO - Running post-action listener: _resource_record_post_action 09:32:45 INFO - ##### 09:32:45 INFO - ##### Running run-tests step. 09:32:45 INFO - ##### 09:32:45 INFO - Running pre-action listener: _resource_record_pre_action 09:32:45 INFO - Running main action method: run_tests 09:32:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:32:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:32:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:32:45 INFO - 2016-04-07 09:32:45.353 screenresolution[7474:165506] starting screenresolution argv=screenresolution get 09:32:45 INFO - 2016-04-07 09:32:45.355 screenresolution[7474:165506] Display 0: 1600x1200x32@60 09:32:45 INFO - 2016-04-07 09:32:45.361 screenresolution[7475:165508] starting screenresolution argv=screenresolution list 09:32:45 INFO - Available Modes on Display 0 09:32:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:32:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:32:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:32:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:32:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:32:45 INFO - Intel Iris: 09:32:45 INFO - Chipset Model: Intel Iris 09:32:45 INFO - Type: GPU 09:32:45 INFO - Bus: Built-In 09:32:45 INFO - VRAM (Dynamic, Max): 1536 MB 09:32:45 INFO - Vendor: Intel (0x8086) 09:32:45 INFO - Device ID: 0x0a2e 09:32:45 INFO - Revision ID: 0x0009 09:32:45 INFO - Displays: 09:32:45 INFO - Display: 09:32:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:32:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:32:45 INFO - Display Serial Number: 1600x1200 60 09:32:45 INFO - Main Display: Yes 09:32:45 INFO - Mirror: Off 09:32:45 INFO - Online: Yes 09:32:45 INFO - Rotation: Supported 09:32:45 INFO - Return code: 0 09:32:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:32:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:32:45 INFO - Python 2.7.3 09:32:45 INFO - Return code: 0 09:32:45 INFO - grabbing minidump binary from tooltool 09:32: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:32:45 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 0x10e97be00>, '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 0x10e7a4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e976b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:32:45 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:32:45 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:32:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:32:45 INFO - Return code: 0 09:32:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:32:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:32:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:32:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:32:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:32:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:32:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:32:46 INFO - Return code: 0 09:32:46 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-0028', '--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:32:46 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-0028 --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:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TNCIERmEZ2/Render', 09:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:46 INFO - 'HOME': '/Users/cltbld', 09:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:46 INFO - 'LOGNAME': 'cltbld', 09:32:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:32:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:46 INFO - 'MOZ_NO_REMOTE': '1', 09:32:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:32:46 INFO - 'NO_EM_RESTART': '1', 09:32:46 INFO - 'PAGER': '/bin/cat', 09:32:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:46 INFO - 'PWD': '/builds/slave/test', 09:32:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:46 INFO - 'SHELL': '/bin/bash', 09:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dK1pcZj5hR/Listeners', 09:32:46 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 09:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:46 INFO - 'USER': 'cltbld', 09:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:46 INFO - 'XPC_FLAGS': '0x0', 09:32:46 INFO - 'XPC_SERVICE_NAME': '0', 09:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:46 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-0028', '--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:32:47 INFO - mozversion application_buildid: 20160406110850 09:32:47 INFO - mozversion application_changeset: 1725b460c3e0de97590cb8764df9ace9115b813e 09:32:47 INFO - mozversion application_display_name: Nightly 09:32:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:32:47 INFO - mozversion application_name: Firefox 09:32:47 INFO - mozversion application_remotingname: firefox 09:32:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:32:47 INFO - mozversion application_vendor: Mozilla 09:32:47 INFO - mozversion application_version: 48.0a1 09:32:47 INFO - mozversion platform_buildid: 20160406110850 09:32:47 INFO - mozversion platform_changeset: 1725b460c3e0de97590cb8764df9ace9115b813e 09:32:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:32:47 INFO - mozversion platform_version: 48.0a1 09:32:47 INFO - using testdate: 1460046767 09:32:47 INFO - actual date: 1460046767 09:32:47 INFO - starting webserver on 'localhost:65381' 09:32:47 INFO - SUITE-START | Running 2 tests 09:32:47 INFO - TEST-START | dromaeo_css 09:32:47 INFO - operating with platform_type : mac_ 09:32:47 INFO - Initialising browser for dromaeo_css test... 09:32:47 INFO - TEST-INFO | started process 7493 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp4sK72j/profile http://localhost:65381/getInfo.html) 09:32:49 INFO - PROCESS | 7493 | __metrics Screen width/height:1600/1200 09:32:49 INFO - PROCESS | 7493 | colorDepth:24 09:32:49 INFO - PROCESS | 7493 | Browser inner width/height: 1024/689 09:32:49 INFO - PROCESS | 7493 | __metrics 09:32:49 INFO - PROCESS | 7493 | 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:32:49 ERROR - PROCESS | 7493 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:32:49 INFO - TEST-INFO | 7493: exit 0 09:32:49 INFO - Browser initialized. 09:32:49 INFO - Running cycle 1/1 for dromaeo_css test... 09:32:49 INFO - TEST-INFO | started process 7495 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp4sK72j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:01 INFO - PROCESS | 7495 | RSS: Main: 236220416 09:33:01 INFO - PROCESS | 7495 | 09:33:53 INFO - PROCESS | 7495 | 1460046833503 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:36:03 INFO - PROCESS | 7495 | Cycle 1(1): loaded http://localhost:65381/tests/dromaeo/cssquery-dojo.html (next: http://localhost:65381/tests/dromaeo/cssquery-ext.html) 09:36:04 INFO - PROCESS | 7495 | RSS: Main: 348102656 09:36:04 INFO - PROCESS | 7495 | 09:39:07 INFO - PROCESS | 7495 | Cycle 1(1): loaded http://localhost:65381/tests/dromaeo/cssquery-ext.html (next: http://localhost:65381/tests/dromaeo/cssquery-jquery.html) 09:39:08 INFO - PROCESS | 7495 | RSS: Main: 364716032 09:39:08 INFO - PROCESS | 7495 | 09:42:09 INFO - PROCESS | 7495 | Cycle 1(1): loaded http://localhost:65381/tests/dromaeo/cssquery-jquery.html (next: http://localhost:65381/tests/dromaeo/cssquery-mootools.html) 09:42:10 INFO - PROCESS | 7495 | RSS: Main: 367767552 09:42:10 INFO - PROCESS | 7495 | 09:45:12 INFO - PROCESS | 7495 | Cycle 1(1): loaded http://localhost:65381/tests/dromaeo/cssquery-mootools.html (next: http://localhost:65381/tests/dromaeo/cssquery-prototype.html) 09:45:13 INFO - PROCESS | 7495 | RSS: Main: 324612096 09:45:13 INFO - PROCESS | 7495 | 09:48:16 INFO - PROCESS | 7495 | Cycle 1(1): loaded http://localhost:65381/tests/dromaeo/cssquery-prototype.html (next: http://localhost:65381/tests/dromaeo/cssquery-yui.html) 09:48:16 INFO - PROCESS | 7495 | RSS: Main: 325595136 09:48:16 INFO - PROCESS | 7495 | 09:51:19 INFO - PROCESS | 7495 | Cycle 1(1): loaded http://localhost:65381/tests/dromaeo/cssquery-yui.html (next: http://localhost:65381/tests/dromaeo/cssquery-dojo.html) 09:51:19 INFO - PROCESS | 7495 | RSS: Main: 371781632 09:51:19 INFO - PROCESS | 7495 | 09:51:19 INFO - PROCESS | 7495 | __start_tp_report 09:51:19 INFO - PROCESS | 7495 | _x_x_mozilla_page_load 09:51:19 INFO - PROCESS | 7495 | _x_x_mozilla_page_load_details 09:51:19 INFO - PROCESS | 7495 | |i|pagename|runs| 09:51:19 INFO - PROCESS | 7495 | |0;dojo.html;3318.0920970441994;3366.057503898909;3412.829358532073;3415.043787739433;3452.7224926777094;5468.425815289397;5532.474414930575;5573.5819813514;5591.664500129995;5607.467290607391;4515.119551687422;5549.056660367737;5673.602847800646;5703.657780533171;5712.400197979215;5000.97499512502;5023.095842748313;5041.899162016757;5052.040112378636;5195.532402083812;5127.307813445174;5131.102057139986;5147.7426128693605;5163.793448262065;5218.32161818963;4968.931679688884;5059.8988020239385;5075.898482030358;5080.263361812535;5085.160948443502;5063.987202559503;5085.364329458817;5086.957173779367;5089.592832573422;5096.311997880276;5017.247412888096;5025.422076461217;5045.293658887758;5067.315912351823;5077.162268225738;5500.064988951888;5523.28197334344;5544.833654990357;5544.889102217973;5553.611247212697;6049.153118563405;6103.405545021544;6122.2761798032625;6124.265088189446;6145.907811382788;6010.158577799111;6152.077188421727;6169.660668663222;6177.567570270083;6183.134361189437;5679.858003549903;5702.287214098238;5703.686297253649;5715.0855863062125;5758.798442054508;6090.878182436415;6129.695011270001;6176.104464852571;6237.282712488031;6249.468795152372;6018.8194354169445;6021.066734656135;6028.819135425944;6031.638101713911;6047.758089676364;6156.722947467375;6158.938410615926;6162.137300777895;6166.568340216142;6174.845628859314;5867.823965281048;5964.582479226499;6039.698015099315;6091.390860913962;6122.00175312775;6095.950922681388;6102.458955353496;6131.338785408118;6140.403495091308;6145.8156255312415;4846.491118432514;4846.684965477232;4850.83022094225;4872.683275587146;4880.731559764247;4244.766537840386;4285.785710714452;4350.1734670412525;4376.715513491613;4400.845970391021;5470;5490.780368785284;5505.091659876076;5510.228568000483;5512.6692398456225;6131.417515336036;6169.660668663177;6192.566520343623;6200.410960958701;6218.626882387071;5331.600129990266;5401.702906340189;5483.150111732688;5490.0941344677685;5502.5597952163735;5271.499207575274;5276.736163191825;5277.656952298088;5278.023565640369;5288.338957630296;6251.374862513712;6266.717997690023;6270.9372906271265;6285.9057114143325;6287.088372186099;6046.2139921809885;6108.908366374597;6113.385090542009;6147.862645410613;6149.96925015372;6209.937900620936;6279.748810047547;6283.685815709287;6287.182666253358;6290.150829637941;6036.154938308552;6037.124616930609;6048.516118710405;6068.453839154321;6078.057901025348;5966.224390829165;6052.213212282375;6071.666058366833;6109.920916985209;6130.979101201984;5265.52610265078;5950.553708471968;5952.940470595412;5960.880782384242;5992;5900.173975643499;5914.083317085859;5919.378465261258;5922.555808314308;5947.01874190771;5225.033368826779;5645.661260324394;5704.914426283692;5761.596688231951;5867.73595188209;5897.262842144732;5900.970495147497;5954.523638108854;5959.702014899324;5997.070454079625;5796.159556864147;5798.94201057984;5799.188113664006;5821.650700957956;5823.301203855564 09:51:19 INFO - PROCESS | 7495 | |1;ext.html;441.4173291255544;541.5721579951837;566.9567994889405;635.2567496029645;641.8010416770801;39105.608943910585;39516.60483395165;39527.60472395283;39536.802315988454;39647.40528892065;34656.65343346572;43250.351244731355;43285.91785205372;43421.78289108558;43465.7826710866;9612.18296444803;9843.950780246125;9857.309988300822;9861.359011664255;9886.50567471627;9571.42571445712;9692;9739.951300243489;9834.967328430483;9855.852162217572;9822.950885245564;9849.113579777819;9893.406395616286;9896.455694936776;9902.009799020112;9790.9510452448;9841.704748857546;9855.507224638775;9864.013598640151;9867.40795552262;9742.707718768448;9841.55712992917;9904;9914.107730304235;9923.206143508502;40436.79781601073;40868.79565602183;40963.7951810239;40965.385519217234;40972.79513602413;2413.6138217885054;2423.75762423758;3208.51872219169;3350.983245083759;3392.3384939936723;26904.730952690417;27327.726722732918;27792;27900;27965.021224257052;2773;2794.790390720704;2799.692033876272;2904.983255860432;2983.955615534567;371768;372068;372560.13719931495;372924;374454;56876;61907.690461547405;62413.87930603452;65823.01265481021;66923;62855.37144628587;63386;63475;63529.682351587944;63667.68166159232;16443.5889102771;16867.325306987583;16873.493795186165;16878.156092195342;16892.40876569314;12786.041046921331;12831.294278814568;12859.16415432994;12861.742765144645;12916.41876115577;20092.296769613287;20427.182912683624;20434.386968390972;20450.079746411448;20461;6001.5798894077;6573.605583664996;7155.39179169766;7529.171791102975;7854.528728276322;851.9403641745018;954.5131982688879;968.675493709601;1008.6992852559897;1100.8238681810872;26982.779287198464;29366.26584335366;29698.77215020188;29978.70021299803;30019.549706754417;993.1508560181086;1080.5191689698008;1092.464692300767;1106.2035334559105;1188.2454641302836;11398.560202889592;11557.76884462306;14777.113373197642;15156.545303640909;15230.619234519225;546422;547055;554911;555387;555967;58871;59211.70394148002;59244.40755592475;59298.70350648219;59326;1139.6694958461953;1318.723068155679;1425.6222101143205;1436.425429828077;1453.273363318362;28114.718852812028;28177.69050194082;28743.712562874105;29250.853745731136;29670.258243544085;47212.819679508284;47394.81512962204;47526.76236618795;47540;47670.56988290357;372152;377370;377805;378114;378354;23806.642900356856;31161.108082660732;31553.109268063487;31689.841550792098;32177.195570109998;35075;42688.14623707571;43362;44234.55765442304;44468.77765611151;34768.30463390667;35991.74028908976;36458.63541364553;36684.26631467409;36765.44851827278;34814;35226.823865880506;35438.64561354354;36271.27457450889;36402.45396319004;35475.290494190485;35639.82180089187;36079.27841443209;36345;36373.636263637025;30623.387532249675;31858.36283274275;32087.518687220178;32100.678993209767;32353.35293294168;21988.450288742908;23905.201967506197;24109.276721698378;24354.269371918872;24617.63073553934 09:51:19 INFO - PROCESS | 7495 | |2;jquery.html;364.39692309228235;365.6252341350117;367.4231456613117;376.22685381540924;376.5537837662372;19069.618607627857;19820;19991.100400481977;20459.18163273471;20479.897600512013;19162.520936976547;20095.497612559655;20805.895970520127;20902.790972090275;20931.267405640807;5606.24315717377;5741.282339707536;5779.422057794229;5808.970955145219;5820.185174075633;4112.917741645166;4341.674374421931;4426.136903303857;6358.77744278948;6379.904301435482;6168.259808822948;6313.589616674924;6315.873682526347;6319.146915166462;6484.0598113273545;6045.12345709874;6257.9374206258035;6258.4680302174365;6284.842878928018;6332.461740752042;5421.783128674848;5447.237386765856;5451.182322651594;5461.726913654302;5471.644343117724;24588.877055614787;24841.25476235716;25142.748572514225;25214.369640758945;25241.62137567938;2104.084723145437;2112.493001679601;2195.1438938813717;2288.5079707862888;2289.095807156181;13844.377003034788;14179.007469477141;14265.857341426557;14302.356393962193;14690.485832995793;1507.8492150784944;1658.9253483593147;1665.641886994302;1706.2663054886393;1796.5688234823679;98230.5088474553;99373.50313248459;99961;100902.49548752209;102248;8981.236594889377;10047.346922450017;10371.547983282346;10377.481125943672;10461.529231184615;12674.429650665743;12705.618831435071;12716.237025778482;12720.363981800872;12756.681082972815;8291.14142464865;8524.14758524155;8525.829483410296;8592.140785921483;8629;8816.559172041372;8826.352094371663;8911.15344042315;8911.955440222757;8927.642894284156;5810.564207684441;5909.6158749681135;5912.704364781744;5915.970420147872;5978.282606087298;1735.8958462492292;1758.2175931710522;1762.4536393717988;1767.407918347368;1769.5841477252836;925.6528801699362;938.7653086728318;951.3150531617224;958.7603099225194;963.7783309838776;15546.533603991898;19647.803521964597;20241.493962651053;20302.79697203009;20567.074481648062;815.0341844913796;998.7103739915489;1006.9848952265722;1013.807376598444;1016.4511163971521;10315.896841031643;11134.3152341174;11183.329000259848;11184.496697648625;11276.1542884284;536352;581596;581659;613869;617222;57056.42943570511;71186;78339.60830195813;89154.55422722845;95444;1037.4605205293205;1045.7856139491387;1057.0402871958486;1060.9363438193734;1062.0547712535695;17414.868033577775;17530.56173595619;17951.281948722426;18115.728264075788;18145.727814082536;31135.688643114187;44213.77893110514;46236.7688161557;46321.073578528914;46857;60583.69708151431;61545.76908461716;61572.69213653903;61664.76670466655;61862;7451.3293803557945;7627.923720762943;7669.769906902802;7821.530708157528;7998.440109192301;12083.093767967262;12499.56251531192;12657.430415631503;12824.487020519075;12929.482820687068;5099.592032637454;7694.846103078019;9122.771930701785;10454.529546170288;11049.834252486058;10417.405640346593;10615.628453004108;10841.123362096529;11017.394043327695;11070.667879963614;5851.297844258716;7137.643117843983;10915.290506117077;11142.944285278521;11255.212135150461;7439.851202976019;7571.432142589438;7639.465237433326;7643.1974642663945;7665.616719163908;10737.03366697001;11011.669649910515;11012.614558490415;11020;11107.444627768418 09:51:19 INFO - PROCESS | 7495 | |3;mootools.html;1422.0401229170307;1539.4842727686225;1558.2053152892024;1794.237449084139;1938.476611314945;7980.042394912604;8663.350248731347;8834;8837.558122093902;8853.114688531145;8476.618552165151;8710.651573937035;8752.956235218815;8754.255888249507;8774.429662071962;2196.198387588535;2414.7947424468853;2454.4845582427665;2474.0969546115716;2476.4675594747127;2184.6722991551237;2422.2127808462233;2488.983923022349;2502.8248022638422;2506.4485813121173;2395.8442701224367;2416.214730212679;2463.408781892351;2469.938251543708;2474.134053081425;2426.733059363469;2462.5567397868376;2465.050955382173;2474.826762126652;2480.1567467061104;2420.4674971506242;2462.322861213163;2470.629405589176;2472.295395812185;2475.9752402475924;8760.8685869712;8822.911770882338;8854.734357969206;8858.86711699325;8872.911270887273;4673.836415725434;5397;5531.944680553223;5556.416576259475;5578.414266501999;3507.684308412255;3635.327464419091;3687.907802304937;3696.131409118855;3699.926001479955;1049.9947500262526;1061.0821639281965;1072.774717309366;1075.9623413180502;1081.7403823082482;12065.457054432572;12133.090018248668;12152.392380380945;12193.024558035337;12221.083418743541;4867.951320486821;4911.926321105187;4932.605391568666;4976.477469865612;4983.053219888282;10248.23138264633;10256.897431025594;10264.486775661187;10271.9486402569;10423.895761042444;5453.400125986218;5522.337319521683;5558.916616250759;5559.58303127259;5567.638103523258;4445.755483448442;4602.723836569817;4827.493113223061;4889.095517329305;4944.975275123601;3827.75119617224;3921.8235179416993;3953.6244056814558;3956.149427873021;3974.4833171687496;1271.2880786759445;1277.2847205564913;1286.292539103508;1291.1607455154037;1298.889594384469;1115.3140818396628;1314.664760486089;1364.7065880835469;1396.8253968253969;1396.8393634731933;8206.179382061866;8316.334693224528;8378.748637540763;8400.663973441116;8405.949256342958;518.3080587415767;519.6648161935606;532.3611666000814;534.8395481355577;536.7557761218636;6164.753409863555;6193.473554747807;6221.782237621662;6230.7819226327765;6231.563790534619;10381.273310868317;10774.299670521392;10895.073918716998;10899.509522071527;10902.855200204023;10425.957404259514;10673.092787113183;10730.946345268223;10764.461776911123;10766.623168189233;8279.296259817984;8376.748697539084;8411.789705257417;8444.66221351139;8446.113158118309;7615.276548727862;7789.4157938155995;7979;7996.56018918965;8004.159563245776;8141.470804397695;9288.838895138108;9608.95195524018;9663.613455461984;9761.707148785548;9745.512724363894;9945.905950345312;9974.251931105342;10027.949860250652;10081.848772268568;6758.425533829482;6854.9657251713425;7359.705611775683;7583.620818959141;7653.732119375795;8112.878306825521;8197.426180167331;8234.505929644241;8240.340772738047;8418.284445822173;7196.0645116134565;7275.272472752682;7297.671604777692;7397.334239918433;7452.962735186289;7099.574025558483;7159.892601610876;7163.498555101093;7184.712611495691;7217.711291548279;6890.10428644273;7675.61621918913;7676;7688.115866675404;7703.229677032252;5814.244148260699;6384;6575.421898744171;6992.16094068715;7004.649767511706;6456.838579035561;6621.370969757865;6630.569013014138;6642.734290628514;6645.202575690754 09:51:19 INFO - PROCESS | 7495 | |4;prototype.html;326.1015901192215;327.6772379206481;330.36240056690576;334.7338865601846;337.11171064245707;6381.553291269613;6423.389777971097;6429.742810287588;6469.70886310116;6478.449331806791;6391.488680905516;6401.295857455676;6413.743450261995;6423.807285781422;6434.935650643492;4528.43394575678;4654.4647365553055;4659.906801863961;4746.549077837617;4761.2143996240575;4720.669553131283;4733.313669517918;4733.81064757411;4747.382840230765;4750.643701722367;4671.743054132022;4674.696144750597;4676.158291507527;4689.109069276827;4706.1999460091865;4523.8642840714665;4566.063956888863;4683.2038553446;4695.1548721230165;4696.812127514895;4047.514298284225;4049.230646177217;4054.1688953764406;4057.5536690963972;4073.3279008963486;6064.241969753781;6133.69331533426;6198.721057552421;6205.007198848208;6210.937890621081;4712.421338851489;5489.039418101856;5604.355499117612;5609.887802243973;5614.494695477386;6888.793336199922;6892.758753443605;6892.827679308008;6904.758333458305;6905.75829845958;4470.41884555009;4619.699719518254;4646.326282689025;4653.53464653532;4679.110968915929;5846.883062338772;7113.857722845514;7155.856882862366;7209.279072092853;7215.603141827198;3978.9455794214555;4229.386738922839;4715.504871988392;4722.008378240539;4743.169945259635;4781.641376896677;4802.255650374198;4803.5916947059895;4803.639727020487;4810.903781924412;5641.210230567724;5651.406602306699;5656.519195868315;5666.660000399989;5682.204491371212;6647.734090636321;6899.103116594811;6902.240753517109;6924.376806087476;6938.098047253926;1449.6665766873678;1658.635100277937;1659.3860271699548;1672.9711227595185;1676.8407001334854;1074.3661239868359;1081.691717860406;1103.1175059952006;1104.1056744037355;1104.7238190452388;5039.949600504021;6106.145139680449;6283.842903927347;6311.274203466568;6330.905036424458;7190.784276471714;7198.172210195789;7235.421166306788;7236.457265705093;7270.382017528464;6303.369663033752;6310.4005119514495;6320.4311611955145;6324.2410910691015;6334.429901308904;3766.962330376716;3813.790241536742;3819.980900095482;3825.0437390652337;3832.96509942314;7118.5017048806085;7371.262873712693;7379.963100184572;7410.18487966334;7423.035005449257;5851.502622277069;7337.816554586071;7361.6319184040585;7370.742024029133;7372.96313518429;7427.294407031323;7480.812979675552;7484.251574842581;7486.5508069516;7489.550626962399;7097.432205423657;7186;7215.639218039182;7240.782776516713;7247.275272472711;6852.691628876612;7130.609531141377;7151.104957186454;7157.105361829771;7172.784816455514;7437.51656142349;7482.95238666598;7483.925160748323;7484.663190156347;7497.737579184703;4491.3487544305235;4558.407407037064;4561.407017087758;4562.977185114053;4566.018306064212;7171.211166771755;7171.928280717335;7172.354488096096;7176.995220669006;7214.386777124003;6742.542324696864;6924.169099708067;6941.444684425335;6944.791656250321;6956;6840.31596840312;6903.827404314932;6911.481638877205;6915.965420172867;6928.930710692828;5505.9724701376235;6771.187457505131;6778.4916131289365;6821.079154314104;6824.488163387667;5956.344802071855;6617.338266173344;6907.102076729993;6941.958706194111;6945.444364450731;6520.608763474207;6555.3772391622715;6645.468362531083;6686.063951046955;6777.542828291941 09:51:19 INFO - PROCESS | 7495 | |5;yui.html;680.2924958043635;717.2791344698577;726.5531698005728;741.9220981796909;772.4322622872188;14452.78320825187;14646.633834154152;14662.926685366585;14686.118832870023;14692.853071469282;13174.802377964339;13342.332883355843;13531.729365412686;13544.932275338635;13547.119437236574;712.3553184368155;899.177252813678;900.6532484993292;902.3187493442442;903.6701603914589;888.9422187557788;891.8840550728396;904.6562306323588;908.7001289574424;909.7498187998287;950.021477877787;959.501059449086;960.903909609037;962.4033099478333;962.7737481691797;944.9558238146852;952.538019060755;954.0840792838883;955.1833182628891;956.9951550871592;706.0151089230546;709.2800807180715;711.008143639625;712.8182313510074;714.039616715522;12729.809052864215;12843.743125137538;12850.742985140338;12884.935575322063;12909.160904541175;11050.392228427434;11070.723231919186;11274.718132046764;11474.254173478697;11514.827277590843;9265.536723163883;9392.812143757155;9434.05659434051;9443.575039123187;9670.468124253162;3401.608814986259;3402.4896265560114;3406.744494162948;3418.6410426905068;3503.562054743157;67988.30029249258;68722.65638671824;74604;77985.83021254743;78468;9575.616975321049;9651;9883.308168428284;9942.850857237147;10342.224333175041;17055.744163837553;20203.494912627302;20695.79304206939;20741.58516829655;21144;7454.40364770817;7467.215942326086;7547.547147171077;7589.051368578927;7655.961720191363;4050.635442810102;4051.4530538376944;4077.7757223352405;4106.2198182345255;4327.653787696977;3532.6643968822923;3581.230035542371;3584.766990145632;3591.8204089795404;3596.5504311961004;634.1724050114552;657.6448717692392;659.4625380315028;668.1314291421133;669.10340144207;527.2060059941218;577.0910815465659;590.852286928268;596.9701514924324;605.5700452678559;8093.312068474128;8200.548969806718;8295.087540370554;8340.833183336299;8360.247577718035;500.5395036566368;576.0696475192534;582.8775957048982;596.0373995996434;598.2855101797805;3625.3655610268097;3824.9426258606145;3876.534815822063;3912.550056743511;4002.239574480839;59840.50398740066;76578.61710691522;80609.59695201604;80798.59600701959;80853;74842;75527.24472755312;75565.62217188989;75660.62169689116;76151.85772213421;66902;67241.66379168072;67247.66376168186;67540.6622966882;68204.65897670579;7582.128055273714;7679.347255483346;7821.960890195513;7891.210878912063;8019.557962282462;49143.508564915326;49338.75330623468;49971.500284996684;51621;52035.479645203064;58043.70978145082;58361.4163858356;66773.32106807585;67165.32834671786;68844.74333736501;10994.395308258123;11020.834687479533;11130.944345278222;11134.3876086816;11294.774104518028;11764.588239411922;11934.701632458911;11949.283043017447;12155.392230388623;12435;11293.548258069914;11321.433928303717;11334.829977550178;11433.542658293574;11728.765424691288;11405.828912566152;11696.766064678828;11794.646160615195;11833.881661183623;12005.039596832406;11312.83030754523;11332;11417.200795944203;11509.53961841517;11870.109741769575;9508.857367139637;9562.378445401027;9850.012981566337;9904;10096.444695541815;9730.36752611078;9753.366031207948;9777.511124443607;9786.363886347364;9808.264380171373 09:51:19 INFO - PROCESS | 7495 | __end_tp_report 09:51:19 INFO - PROCESS | 7495 | __start_cc_report 09:51:19 INFO - PROCESS | 7495 | _x_x_mozilla_cycle_collect,1120 09:51:19 INFO - PROCESS | 7495 | __end_cc_report 09:51:19 INFO - PROCESS | 7495 | __startTimestamp1460047879690__endTimestamp 09:51:19 INFO - PROCESS | 7495 | 09:51:19 INFO - PROCESS | 7495 | ------- Summary: start ------- 09:51:19 INFO - PROCESS | 7495 | Number of tests: 6 09:51:19 INFO - PROCESS | 7495 | 09:51:19 INFO - PROCESS | 7495 | [#0] dojo.html Cycles:165 Average:5614.85 Median:5799.19 stddev:630.16 (10.9%) stddev-sans-first:605.78 09:51:19 INFO - PROCESS | 7495 | Values: 3318.1 3366.1 3412.8 3415.0 3452.7 5468.4 5532.5 5573.6 5591.7 5607.5 4515.1 5549.1 5673.6 5703.7 5712.4 5001.0 5023.1 5041.9 5052.0 5195.5 5127.3 5131.1 5147.7 5163.8 5218.3 4968.9 5059.9 5075.9 5080.3 5085.2 5064.0 5085.4 5087.0 5089.6 5096.3 5017.2 5025.4 5045.3 5067.3 5077.2 5500.1 5523.3 5544.8 5544.9 5553.6 6049.2 6103.4 6122.3 6124.3 6145.9 6010.2 6152.1 6169.7 6177.6 6183.1 5679.9 5702.3 5703.7 5715.1 5758.8 6090.9 6129.7 6176.1 6237.3 6249.5 6018.8 6021.1 6028.8 6031.6 6047.8 6156.7 6158.9 6162.1 6166.6 6174.8 5867.8 5964.6 6039.7 6091.4 6122.0 6096.0 6102.5 6131.3 6140.4 6145.8 4846.5 4846.7 4850.8 4872.7 4880.7 4244.8 4285.8 4350.2 4376.7 4400.8 5470.0 5490.8 5505.1 5510.2 5512.7 6131.4 6169.7 6192.6 6200.4 6218.6 5331.6 5401.7 5483.2 5490.1 5502.6 5271.5 5276.7 5277.7 5278.0 5288.3 6251.4 6266.7 6270.9 6285.9 6287.1 6046.2 6108.9 6113.4 6147.9 6150.0 6209.9 6279.7 6283.7 6287.2 6290.2 6036.2 6037.1 6048.5 6068.5 6078.1 5966.2 6052.2 6071.7 6109.9 6131.0 5265.5 5950.6 5952.9 5960.9 5992.0 5900.2 5914.1 5919.4 5922.6 5947.0 5225.0 5645.7 5704.9 5761.6 5867.7 5897.3 5901.0 5954.5 5959.7 5997.1 5796.2 5798.9 5799.2 5821.7 5823.3 09:51:19 INFO - PROCESS | 7495 | 09:51:19 INFO - PROCESS | 7495 | [#1] ext.html Cycles:180 Average:58521.64 Median:372356.07 stddev:117368.64 (31.5%) stddev-sans-first:117616.88 09:51:19 INFO - PROCESS | 7495 | Values: 441.4 541.6 567.0 635.3 641.8 39105.6 39516.6 39527.6 39536.8 39647.4 34656.7 43250.4 43285.9 43421.8 43465.8 9612.2 9844.0 9857.3 9861.4 9886.5 9571.4 9692.0 9740.0 9835.0 9855.9 9823.0 9849.1 9893.4 9896.5 9902.0 9791.0 9841.7 9855.5 9864.0 9867.4 9742.7 9841.6 9904.0 9914.1 9923.2 40436.8 40868.8 40963.8 40965.4 40972.8 2413.6 2423.8 3208.5 3351.0 3392.3 26904.7 27327.7 27792.0 27900.0 27965.0 2773.0 2794.8 2799.7 2905.0 2984.0 371768.0 372068.0 372560.1 372924.0 374454.0 56876.0 61907.7 62413.9 65823.0 66923.0 62855.4 63386.0 63475.0 63529.7 63667.7 16443.6 16867.3 16873.5 16878.2 16892.4 12786.0 12831.3 12859.2 12861.7 12916.4 20092.3 20427.2 20434.4 20450.1 20461.0 6001.6 6573.6 7155.4 7529.2 7854.5 851.9 954.5 968.7 1008.7 1100.8 26982.8 29366.3 29698.8 29978.7 30019.5 993.2 1080.5 1092.5 1106.2 1188.2 11398.6 11557.8 14777.1 15156.5 15230.6 546422.0 547055.0 554911.0 555387.0 555967.0 58871.0 59211.7 59244.4 59298.7 59326.0 1139.7 1318.7 1425.6 1436.4 1453.3 28114.7 28177.7 28743.7 29250.9 29670.3 47212.8 47394.8 47526.8 47540.0 47670.6 372152.0 377370.0 377805.0 378114.0 378354.0 23806.6 31161.1 31553.1 31689.8 32177.2 35075.0 42688.1 43362.0 44234.6 44468.8 34768.3 35991.7 36458.6 36684.3 36765.4 34814.0 35226.8 35438.6 36271.3 36402.5 35475.3 35639.8 36079.3 36345.0 36373.6 30623.4 31858.4 32087.5 32100.7 32353.4 21988.5 23905.2 24109.3 24354.3 24617.6 09:51:19 INFO - PROCESS | 7495 | 09:51:19 INFO - PROCESS | 7495 | [#2] jquery.html Cycles:180 Average:31893.32 Median:365.01 stddev:96525.66 (26444.6%) stddev-sans-first:96767.40 09:51:19 INFO - PROCESS | 7495 | Values: 364.4 365.6 367.4 376.2 376.6 19069.6 19820.0 19991.1 20459.2 20479.9 19162.5 20095.5 20805.9 20902.8 20931.3 5606.2 5741.3 5779.4 5809.0 5820.2 4112.9 4341.7 4426.1 6358.8 6379.9 6168.3 6313.6 6315.9 6319.1 6484.1 6045.1 6257.9 6258.5 6284.8 6332.5 5421.8 5447.2 5451.2 5461.7 5471.6 24588.9 24841.3 25142.7 25214.4 25241.6 2104.1 2112.5 2195.1 2288.5 2289.1 13844.4 14179.0 14265.9 14302.4 14690.5 1507.8 1658.9 1665.6 1706.3 1796.6 98230.5 99373.5 99961.0 100902.5 102248.0 8981.2 10047.3 10371.5 10377.5 10461.5 12674.4 12705.6 12716.2 12720.4 12756.7 8291.1 8524.1 8525.8 8592.1 8629.0 8816.6 8826.4 8911.2 8912.0 8927.6 5810.6 5909.6 5912.7 5916.0 5978.3 1735.9 1758.2 1762.5 1767.4 1769.6 925.7 938.8 951.3 958.8 963.8 15546.5 19647.8 20241.5 20302.8 20567.1 815.0 998.7 1007.0 1013.8 1016.5 10315.9 11134.3 11183.3 11184.5 11276.2 536352.0 581596.0 581659.0 613869.0 617222.0 57056.4 71186.0 78339.6 89154.6 95444.0 1037.5 1045.8 1057.0 1060.9 1062.1 17414.9 17530.6 17951.3 18115.7 18145.7 31135.7 44213.8 46236.8 46321.1 46857.0 60583.7 61545.8 61572.7 61664.8 61862.0 7451.3 7627.9 7669.8 7821.5 7998.4 12083.1 12499.6 12657.4 12824.5 12929.5 5099.6 7694.8 9122.8 10454.5 11049.8 10417.4 10615.6 10841.1 11017.4 11070.7 5851.3 7137.6 10915.3 11142.9 11255.2 7439.9 7571.4 7639.5 7643.2 7665.6 10737.0 11011.7 11012.6 11020.0 11107.4 09:51:19 INFO - PROCESS | 7495 | 09:51:19 INFO - PROCESS | 7495 | [#3] mootools.html Cycles:180 Average:6026.04 Median:533.60 stddev:3224.69 (604.3%) stddev-sans-first:3215.17 09:51:19 INFO - PROCESS | 7495 | Values: 1422.0 1539.5 1558.2 1794.2 1938.5 7980.0 8663.4 8834.0 8837.6 8853.1 8476.6 8710.7 8753.0 8754.3 8774.4 2196.2 2414.8 2454.5 2474.1 2476.5 2184.7 2422.2 2489.0 2502.8 2506.4 2395.8 2416.2 2463.4 2469.9 2474.1 2426.7 2462.6 2465.1 2474.8 2480.2 2420.5 2462.3 2470.6 2472.3 2476.0 8760.9 8822.9 8854.7 8858.9 8872.9 4673.8 5397.0 5531.9 5556.4 5578.4 3507.7 3635.3 3687.9 3696.1 3699.9 1050.0 1061.1 1072.8 1076.0 1081.7 12065.5 12133.1 12152.4 12193.0 12221.1 4868.0 4911.9 4932.6 4976.5 4983.1 10248.2 10256.9 10264.5 10271.9 10423.9 5453.4 5522.3 5558.9 5559.6 5567.6 4445.8 4602.7 4827.5 4889.1 4945.0 3827.8 3921.8 3953.6 3956.1 3974.5 1271.3 1277.3 1286.3 1291.2 1298.9 1115.3 1314.7 1364.7 1396.8 1396.8 8206.2 8316.3 8378.7 8400.7 8405.9 518.3 519.7 532.4 534.8 536.8 6164.8 6193.5 6221.8 6230.8 6231.6 10381.3 10774.3 10895.1 10899.5 10902.9 10426.0 10673.1 10730.9 10764.5 10766.6 8279.3 8376.7 8411.8 8444.7 8446.1 7615.3 7789.4 7979.0 7996.6 8004.2 8141.5 9288.8 9609.0 9663.6 9761.7 9745.5 9945.9 9974.3 10027.9 10081.8 6758.4 6855.0 7359.7 7583.6 7653.7 8112.9 8197.4 8234.5 8240.3 8418.3 7196.1 7275.3 7297.7 7397.3 7453.0 7099.6 7159.9 7163.5 7184.7 7217.7 6890.1 7675.6 7676.0 7688.1 7703.2 5814.2 6384.0 6575.4 6992.2 7004.6 6456.8 6621.4 6630.6 6642.7 6645.2 09:51:19 INFO - PROCESS | 7495 | 09:51:19 INFO - PROCESS | 7495 | [#4] prototype.html Cycles:180 Average:5635.00 Median:6322.34 stddev:1787.65 (28.3%) stddev-sans-first:1747.69 09:51:19 INFO - PROCESS | 7495 | Values: 326.1 327.7 330.4 334.7 337.1 6381.6 6423.4 6429.7 6469.7 6478.4 6391.5 6401.3 6413.7 6423.8 6434.9 4528.4 4654.5 4659.9 4746.5 4761.2 4720.7 4733.3 4733.8 4747.4 4750.6 4671.7 4674.7 4676.2 4689.1 4706.2 4523.9 4566.1 4683.2 4695.2 4696.8 4047.5 4049.2 4054.2 4057.6 4073.3 6064.2 6133.7 6198.7 6205.0 6210.9 4712.4 5489.0 5604.4 5609.9 5614.5 6888.8 6892.8 6892.8 6904.8 6905.8 4470.4 4619.7 4646.3 4653.5 4679.1 5846.9 7113.9 7155.9 7209.3 7215.6 3978.9 4229.4 4715.5 4722.0 4743.2 4781.6 4802.3 4803.6 4803.6 4810.9 5641.2 5651.4 5656.5 5666.7 5682.2 6647.7 6899.1 6902.2 6924.4 6938.1 1449.7 1658.6 1659.4 1673.0 1676.8 1074.4 1081.7 1103.1 1104.1 1104.7 5039.9 6106.1 6283.8 6311.3 6330.9 7190.8 7198.2 7235.4 7236.5 7270.4 6303.4 6310.4 6320.4 6324.2 6334.4 3767.0 3813.8 3820.0 3825.0 3833.0 7118.5 7371.3 7380.0 7410.2 7423.0 5851.5 7337.8 7361.6 7370.7 7373.0 7427.3 7480.8 7484.3 7486.6 7489.6 7097.4 7186.0 7215.6 7240.8 7247.3 6852.7 7130.6 7151.1 7157.1 7172.8 7437.5 7483.0 7483.9 7484.7 7497.7 4491.3 4558.4 4561.4 4563.0 4566.0 7171.2 7171.9 7172.4 7177.0 7214.4 6742.5 6924.2 6941.4 6944.8 6956.0 6840.3 6903.8 6911.5 6916.0 6928.9 5506.0 6771.2 6778.5 6821.1 6824.5 5956.3 6617.3 6907.1 6942.0 6945.4 6520.6 6555.4 6645.5 6686.1 6777.5 09:51:19 INFO - PROCESS | 7495 | 09:51:19 INFO - PROCESS | 7495 | [#5] yui.html Cycles:180 Average:17246.27 Median:645.91 stddev:23518.71 (3641.2%) stddev-sans-first:23551.79 09:51:19 INFO - PROCESS | 7495 | Values: 680.3 717.3 726.6 741.9 772.4 14452.8 14646.6 14662.9 14686.1 14692.9 13174.8 13342.3 13531.7 13544.9 13547.1 712.4 899.2 900.7 902.3 903.7 888.9 891.9 904.7 908.7 909.7 950.0 959.5 960.9 962.4 962.8 945.0 952.5 954.1 955.2 957.0 706.0 709.3 711.0 712.8 714.0 12729.8 12843.7 12850.7 12884.9 12909.2 11050.4 11070.7 11274.7 11474.3 11514.8 9265.5 9392.8 9434.1 9443.6 9670.5 3401.6 3402.5 3406.7 3418.6 3503.6 67988.3 68722.7 74604.0 77985.8 78468.0 9575.6 9651.0 9883.3 9942.9 10342.2 17055.7 20203.5 20695.8 20741.6 21144.0 7454.4 7467.2 7547.5 7589.1 7656.0 4050.6 4051.5 4077.8 4106.2 4327.7 3532.7 3581.2 3584.8 3591.8 3596.6 634.2 657.6 659.5 668.1 669.1 527.2 577.1 590.9 597.0 605.6 8093.3 8200.5 8295.1 8340.8 8360.2 500.5 576.1 582.9 596.0 598.3 3625.4 3824.9 3876.5 3912.6 4002.2 59840.5 76578.6 80609.6 80798.6 80853.0 74842.0 75527.2 75565.6 75660.6 76151.9 66902.0 67241.7 67247.7 67540.7 68204.7 7582.1 7679.3 7822.0 7891.2 8019.6 49143.5 49338.8 49971.5 51621.0 52035.5 58043.7 58361.4 66773.3 67165.3 68844.7 10994.4 11020.8 11130.9 11134.4 11294.8 11764.6 11934.7 11949.3 12155.4 12435.0 11293.5 11321.4 11334.8 11433.5 11728.8 11405.8 11696.8 11794.6 11833.9 12005.0 11312.8 11332.0 11417.2 11509.5 11870.1 9508.9 9562.4 9850.0 9904.0 10096.4 9730.4 9753.4 9777.5 9786.4 9808.3 09:51:19 INFO - PROCESS | 7495 | -------- Summary: end -------- 09:51:19 INFO - PROCESS | 7495 | 09:51:20 INFO - TEST-INFO | 7495: exit 0 09:51:20 INFO - TEST-OK | dromaeo_css | took 1113037ms 09:51:20 INFO - TEST-START | kraken 09:51:20 INFO - operating with platform_type : mac_ 09:51:20 INFO - Initialising browser for kraken test... 09:51:20 INFO - TEST-INFO | started process 7497 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpVT0yB_/profile http://localhost:65381/getInfo.html) 09:51:22 INFO - PROCESS | 7497 | __metrics Screen width/height:1600/1200 09:51:22 INFO - PROCESS | 7497 | colorDepth:24 09:51:22 INFO - PROCESS | 7497 | Browser inner width/height: 1024/689 09:51:22 INFO - PROCESS | 7497 | __metrics 09:51:22 INFO - PROCESS | 7497 | 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:51:22 ERROR - PROCESS | 7497 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:51:22 INFO - TEST-INFO | 7497: exit 0 09:51:22 INFO - Browser initialized. 09:51:22 INFO - Running cycle 1/1 for kraken test... 09:51:22 INFO - TEST-INFO | started process 7499 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpVT0yB_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:51:34 INFO - PROCESS | 7499 | RSS: Main: 236376064 09:51:34 INFO - PROCESS | 7499 | 09:51:37 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:65381/tests/kraken/driver.html?testName=audio-beat-detection) 09:51:37 INFO - PROCESS | 7499 | RSS: Main: 282648576 09:51:37 INFO - PROCESS | 7499 | 09:51:40 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:65381/tests/kraken/driver.html?testName=audio-dft) 09:51:41 INFO - PROCESS | 7499 | RSS: Main: 294789120 09:51:41 INFO - PROCESS | 7499 | 09:51:44 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:65381/tests/kraken/driver.html?testName=audio-fft) 09:51:45 INFO - PROCESS | 7499 | RSS: Main: 280682496 09:51:45 INFO - PROCESS | 7499 | 09:51:47 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:65381/tests/kraken/driver.html?testName=audio-oscillator) 09:51:47 INFO - PROCESS | 7499 | RSS: Main: 282800128 09:51:47 INFO - PROCESS | 7499 | 09:51:50 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:65381/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:51:50 INFO - PROCESS | 7499 | RSS: Main: 281821184 09:51:50 INFO - PROCESS | 7499 | 09:51:53 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:65381/tests/kraken/driver.html?testName=imaging-darkroom) 09:51:54 INFO - PROCESS | 7499 | RSS: Main: 282796032 09:51:54 INFO - PROCESS | 7499 | 09:51:56 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:65381/tests/kraken/driver.html?testName=imaging-desaturate) 09:51:57 INFO - PROCESS | 7499 | RSS: Main: 282148864 09:51:57 INFO - PROCESS | 7499 | 09:52:00 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:65381/tests/kraken/driver.html?testName=json-parse-financial) 09:52:00 INFO - PROCESS | 7499 | RSS: Main: 282152960 09:52:00 INFO - PROCESS | 7499 | 09:52:02 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:65381/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:52:03 INFO - PROCESS | 7499 | RSS: Main: 315748352 09:52:03 INFO - PROCESS | 7499 | 09:52:04 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:65381/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:52:05 INFO - PROCESS | 7499 | RSS: Main: 316870656 09:52:05 INFO - PROCESS | 7499 | 09:52:07 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:65381/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:52:08 INFO - PROCESS | 7499 | RSS: Main: 323788800 09:52:08 INFO - PROCESS | 7499 | 09:52:10 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:65381/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:52:11 INFO - PROCESS | 7499 | RSS: Main: 324440064 09:52:11 INFO - PROCESS | 7499 | 09:52:13 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:65381/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:52:14 INFO - PROCESS | 7499 | RSS: Main: 324796416 09:52:14 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | Cycle 1(1): loaded http://localhost:65381/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:65381/tests/kraken/driver.html?testName=ai-astar) 09:52:16 INFO - PROCESS | 7499 | RSS: Main: 388984832 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | __start_tp_report 09:52:16 INFO - PROCESS | 7499 | _x_x_mozilla_page_load 09:52:16 INFO - PROCESS | 7499 | _x_x_mozilla_page_load_details 09:52:16 INFO - PROCESS | 7499 | |i|pagename|runs| 09:52:16 INFO - PROCESS | 7499 | |0;ai-astar;111;108;110;110;112;111;116;110;111;113 09:52:16 INFO - PROCESS | 7499 | |1;audio-beat-detection;158;124;151;191;150;141;142;118;164;120 09:52:16 INFO - PROCESS | 7499 | |2;audio-dft;187;184;184;185;192;263;218;233;249;182 09:52:16 INFO - PROCESS | 7499 | |3;audio-fft;74;80;84;80;61;59;58;79;79;55 09:52:16 INFO - PROCESS | 7499 | |4;audio-oscillator;92;102;168;117;99;79;120;81;78;122 09:52:16 INFO - PROCESS | 7499 | |5;imaging-gaussian-blur;90;84;88;85;87;84;131;83;86;84 09:52:16 INFO - PROCESS | 7499 | |6;imaging-darkroom;93;101;93;93;92;92;91;93;93;110 09:52:16 INFO - PROCESS | 7499 | |7;imaging-desaturate;85;87;84;87;84;85;84;87;86;114 09:52:16 INFO - PROCESS | 7499 | |8;json-parse-financial;55;54;54;53;53;53;52;66;55;51 09:52:16 INFO - PROCESS | 7499 | |9;json-stringify-tinderbox;64;72;70;76;71;69;68;70;70;69 09:52:16 INFO - PROCESS | 7499 | |10;stanford-crypto-aes;82;84;83;84;84;82;84;81;83;83 09:52:16 INFO - PROCESS | 7499 | |11;stanford-crypto-ccm;122;137;122;122;125;122;124;130;135;138 09:52:16 INFO - PROCESS | 7499 | |12;stanford-crypto-pbkdf2;140;128;142;137;138;130;135;140;137;138 09:52:16 INFO - PROCESS | 7499 | |13;stanford-crypto-sha256-iterative;67;68;66;66;67;67;66;68;67;67 09:52:16 INFO - PROCESS | 7499 | __end_tp_report 09:52:16 INFO - PROCESS | 7499 | __start_cc_report 09:52:16 INFO - PROCESS | 7499 | _x_x_mozilla_cycle_collect,1313 09:52:16 INFO - PROCESS | 7499 | __end_cc_report 09:52:16 INFO - PROCESS | 7499 | __startTimestamp1460047936448__endTimestamp 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | ------- Summary: start ------- 09:52:16 INFO - PROCESS | 7499 | Number of tests: 14 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:2.15 (1.9%) stddev-sans-first:2.28 09:52:16 INFO - PROCESS | 7499 | Values: 111.0 108.0 110.0 110.0 112.0 111.0 116.0 110.0 111.0 113.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:150.50 stddev:22.41 (14.9%) stddev-sans-first:23.34 09:52:16 INFO - PROCESS | 7499 | Values: 158.0 124.0 151.0 191.0 150.0 141.0 142.0 118.0 164.0 120.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#2] audio-dft Cycles:10 Average:207.70 Median:205.00 stddev:30.70 (15.0%) stddev-sans-first:31.64 09:52:16 INFO - PROCESS | 7499 | Values: 187.0 184.0 184.0 185.0 192.0 263.0 218.0 233.0 249.0 182.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#3] audio-fft Cycles:10 Average:70.90 Median:79.00 stddev:11.24 (14.2%) stddev-sans-first:11.86 09:52:16 INFO - PROCESS | 7499 | Values: 74.0 80.0 84.0 80.0 61.0 59.0 58.0 79.0 79.0 55.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#4] audio-oscillator Cycles:10 Average:105.80 Median:78.50 stddev:27.52 (35.1%) stddev-sans-first:28.73 09:52:16 INFO - PROCESS | 7499 | Values: 92.0 102.0 168.0 117.0 99.0 79.0 120.0 81.0 78.0 122.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:85.50 stddev:14.50 (17.0%) stddev-sans-first:15.38 09:52:16 INFO - PROCESS | 7499 | Values: 90.0 84.0 88.0 85.0 87.0 84.0 131.0 83.0 86.0 84.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:93.00 stddev:5.92 (6.4%) stddev-sans-first:6.22 09:52:16 INFO - PROCESS | 7499 | Values: 93.0 101.0 93.0 93.0 92.0 92.0 91.0 93.0 93.0 110.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#7] imaging-desaturate Cycles:10 Average:88.30 Median:85.50 stddev:9.12 (10.7%) stddev-sans-first:9.59 09:52:16 INFO - PROCESS | 7499 | Values: 85.0 87.0 84.0 87.0 84.0 85.0 84.0 87.0 86.0 114.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.00 stddev:4.20 (7.8%) stddev-sans-first:4.45 09:52:16 INFO - PROCESS | 7499 | Values: 55.0 54.0 54.0 53.0 53.0 53.0 52.0 66.0 55.0 51.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.00 stddev:3.03 (4.3%) stddev-sans-first:2.35 09:52:16 INFO - PROCESS | 7499 | Values: 64.0 72.0 70.0 76.0 71.0 69.0 68.0 70.0 70.0 69.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:1.05 (1.3%) stddev-sans-first:1.05 09:52:16 INFO - PROCESS | 7499 | Values: 82.0 84.0 83.0 84.0 84.0 82.0 84.0 81.0 83.0 83.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:127.50 stddev:6.68 (5.2%) stddev-sans-first:6.76 09:52:16 INFO - PROCESS | 7499 | Values: 122.0 137.0 122.0 122.0 125.0 122.0 124.0 130.0 135.0 138.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.50 Median:138.00 stddev:4.43 (3.2%) stddev-sans-first:4.51 09:52:16 INFO - PROCESS | 7499 | Values: 140.0 128.0 142.0 137.0 138.0 130.0 135.0 140.0 137.0 138.0 09:52:16 INFO - PROCESS | 7499 | 09:52:16 INFO - PROCESS | 7499 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 09:52:16 INFO - PROCESS | 7499 | Values: 67.0 68.0 66.0 66.0 67.0 67.0 66.0 68.0 67.0 67.0 09:52:16 INFO - PROCESS | 7499 | -------- Summary: end -------- 09:52:16 INFO - PROCESS | 7499 | 09:52:17 INFO - TEST-INFO | 7499: exit 0 09:52:17 INFO - TEST-OK | kraken | took 56964ms 09:52:17 INFO - SUITE-END | took 1170s 09:52:17 INFO - Completed test suite (00:19:30) 09:52:17 INFO - javascript benchmark 09:52:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3318.0920970441994, 3366.057503898909, 3412.829358532073, 3415.043787739433, 3452.7224926777094, 5468.425815289397, 5532.474414930575, 5573.5819813514, 5591.664500129995, 5607.467290607391, 4515.119551687422, 5549.056660367737, 5673.602847800646, 5703.657780533171, 5712.400197979215, 5000.97499512502, 5023.095842748313, 5041.899162016757, 5052.040112378636, 5195.532402083812, 5127.307813445174, 5131.102057139986, 5147.7426128693605, 5163.793448262065, 5218.32161818963, 4968.931679688884, 5059.8988020239385, 5075.898482030358, 5080.263361812535, 5085.160948443502, 5063.987202559503, 5085.364329458817, 5086.957173779367, 5089.592832573422, 5096.311997880276, 5017.247412888096, 5025.422076461217, 5045.293658887758, 5067.315912351823, 5077.162268225738, 5500.064988951888, 5523.28197334344, 5544.833654990357, 5544.889102217973, 5553.611247212697, 6049.153118563405, 6103.405545021544, 6122.2761798032625, 6124.265088189446, 6145.907811382788, 6010.158577799111, 6152.077188421727, 6169.660668663222, 6177.567570270083, 6183.134361189437, 5679.858003549903, 5702.287214098238, 5703.686297253649, 5715.0855863062125, 5758.798442054508, 6090.878182436415, 6129.695011270001, 6176.104464852571, 6237.282712488031, 6249.468795152372, 6018.8194354169445, 6021.066734656135, 6028.819135425944, 6031.638101713911, 6047.758089676364, 6156.722947467375, 6158.938410615926, 6162.137300777895, 6166.568340216142, 6174.845628859314, 5867.823965281048, 5964.582479226499, 6039.698015099315, 6091.390860913962, 6122.00175312775, 6095.950922681388, 6102.458955353496, 6131.338785408118, 6140.403495091308, 6145.8156255312415, 4846.491118432514, 4846.684965477232, 4850.83022094225, 4872.683275587146, 4880.731559764247, 4244.766537840386, 4285.785710714452, 4350.1734670412525, 4376.715513491613, 4400.845970391021, 5470.0, 5490.780368785284, 5505.091659876076, 5510.228568000483, 5512.6692398456225, 6131.417515336036, 6169.660668663177, 6192.566520343623, 6200.410960958701, 6218.626882387071, 5331.600129990266, 5401.702906340189, 5483.150111732688, 5490.0941344677685, 5502.5597952163735, 5271.499207575274, 5276.736163191825, 5277.656952298088, 5278.023565640369, 5288.338957630296, 6251.374862513712, 6266.717997690023, 6270.9372906271265, 6285.9057114143325, 6287.088372186099, 6046.2139921809885, 6108.908366374597, 6113.385090542009, 6147.862645410613, 6149.96925015372, 6209.937900620936, 6279.748810047547, 6283.685815709287, 6287.182666253358, 6290.150829637941, 6036.154938308552, 6037.124616930609, 6048.516118710405, 6068.453839154321, 6078.057901025348, 5966.224390829165, 6052.213212282375, 6071.666058366833, 6109.920916985209, 6130.979101201984, 5265.52610265078, 5950.553708471968, 5952.940470595412, 5960.880782384242, 5992.0, 5900.173975643499, 5914.083317085859, 5919.378465261258, 5922.555808314308, 5947.01874190771, 5225.033368826779, 5645.661260324394, 5704.914426283692, 5761.596688231951, 5867.73595188209, 5897.262842144732, 5900.970495147497, 5954.523638108854, 5959.702014899324, 5997.070454079625, 5796.159556864147, 5798.94201057984, 5799.188113664006, 5821.650700957956, 5823.301203855564], "unit": "score", "name": "dojo.html", "value": 5575.192976938289}, {"lowerIsBetter": false, "replicates": [441.4173291255544, 541.5721579951837, 566.9567994889405, 635.2567496029645, 641.8010416770801, 39105.608943910585, 39516.60483395165, 39527.60472395283, 39536.802315988454, 39647.40528892065, 34656.65343346572, 43250.351244731355, 43285.91785205372, 43421.78289108558, 43465.7826710866, 9612.18296444803, 9843.950780246125, 9857.309988300822, 9861.359011664255, 9886.50567471627, 9571.42571445712, 9692.0, 9739.951300243489, 9834.967328430483, 9855.852162217572, 9822.950885245564, 9849.113579777819, 9893.406395616286, 9896.455694936776, 9902.009799020112, 9790.9510452448, 9841.704748857546, 9855.507224638775, 9864.013598640151, 9867.40795552262, 9742.707718768448, 9841.55712992917, 9904.0, 9914.107730304235, 9923.206143508502, 40436.79781601073, 40868.79565602183, 40963.7951810239, 40965.385519217234, 40972.79513602413, 2413.6138217885054, 2423.75762423758, 3208.51872219169, 3350.983245083759, 3392.3384939936723, 26904.730952690417, 27327.726722732918, 27792.0, 27900.0, 27965.021224257052, 2773.0, 2794.790390720704, 2799.692033876272, 2904.983255860432, 2983.955615534567, 371768.0, 372068.0, 372560.13719931495, 372924.0, 374454.0, 56876.0, 61907.690461547405, 62413.87930603452, 65823.01265481021, 66923.0, 62855.37144628587, 63386.0, 63475.0, 63529.682351587944, 63667.68166159232, 16443.5889102771, 16867.325306987583, 16873.493795186165, 16878.156092195342, 16892.40876569314, 12786.041046921331, 12831.294278814568, 12859.16415432994, 12861.742765144645, 12916.41876115577, 20092.296769613287, 20427.182912683624, 20434.386968390972, 20450.079746411448, 20461.0, 6001.5798894077, 6573.605583664996, 7155.39179169766, 7529.171791102975, 7854.528728276322, 851.9403641745018, 954.5131982688879, 968.675493709601, 1008.6992852559897, 1100.8238681810872, 26982.779287198464, 29366.26584335366, 29698.77215020188, 29978.70021299803, 30019.549706754417, 993.1508560181086, 1080.5191689698008, 1092.464692300767, 1106.2035334559105, 1188.2454641302836, 11398.560202889592, 11557.76884462306, 14777.113373197642, 15156.545303640909, 15230.619234519225, 546422.0, 547055.0, 554911.0, 555387.0, 555967.0, 58871.0, 59211.70394148002, 59244.40755592475, 59298.70350648219, 59326.0, 1139.6694958461953, 1318.723068155679, 1425.6222101143205, 1436.425429828077, 1453.273363318362, 28114.718852812028, 28177.69050194082, 28743.712562874105, 29250.853745731136, 29670.258243544085, 47212.819679508284, 47394.81512962204, 47526.76236618795, 47540.0, 47670.56988290357, 372152.0, 377370.0, 377805.0, 378114.0, 378354.0, 23806.642900356856, 31161.108082660732, 31553.109268063487, 31689.841550792098, 32177.195570109998, 35075.0, 42688.14623707571, 43362.0, 44234.55765442304, 44468.77765611151, 34768.30463390667, 35991.74028908976, 36458.63541364553, 36684.26631467409, 36765.44851827278, 34814.0, 35226.823865880506, 35438.64561354354, 36271.27457450889, 36402.45396319004, 35475.290494190485, 35639.82180089187, 36079.27841443209, 36345.0, 36373.636263637025, 30623.387532249675, 31858.36283274275, 32087.518687220178, 32100.678993209767, 32353.35293294168, 21988.450288742908, 23905.201967506197, 24109.276721698378, 24354.269371918872, 24617.63073553934], "unit": "score", "name": "ext.html", "value": 19203.91328312901}, {"lowerIsBetter": false, "replicates": [364.39692309228235, 365.6252341350117, 367.4231456613117, 376.22685381540924, 376.5537837662372, 19069.618607627857, 19820.0, 19991.100400481977, 20459.18163273471, 20479.897600512013, 19162.520936976547, 20095.497612559655, 20805.895970520127, 20902.790972090275, 20931.267405640807, 5606.24315717377, 5741.282339707536, 5779.422057794229, 5808.970955145219, 5820.185174075633, 4112.917741645166, 4341.674374421931, 4426.136903303857, 6358.77744278948, 6379.904301435482, 6168.259808822948, 6313.589616674924, 6315.873682526347, 6319.146915166462, 6484.0598113273545, 6045.12345709874, 6257.9374206258035, 6258.4680302174365, 6284.842878928018, 6332.461740752042, 5421.783128674848, 5447.237386765856, 5451.182322651594, 5461.726913654302, 5471.644343117724, 24588.877055614787, 24841.25476235716, 25142.748572514225, 25214.369640758945, 25241.62137567938, 2104.084723145437, 2112.493001679601, 2195.1438938813717, 2288.5079707862888, 2289.095807156181, 13844.377003034788, 14179.007469477141, 14265.857341426557, 14302.356393962193, 14690.485832995793, 1507.8492150784944, 1658.9253483593147, 1665.641886994302, 1706.2663054886393, 1796.5688234823679, 98230.5088474553, 99373.50313248459, 99961.0, 100902.49548752209, 102248.0, 8981.236594889377, 10047.346922450017, 10371.547983282346, 10377.481125943672, 10461.529231184615, 12674.429650665743, 12705.618831435071, 12716.237025778482, 12720.363981800872, 12756.681082972815, 8291.14142464865, 8524.14758524155, 8525.829483410296, 8592.140785921483, 8629.0, 8816.559172041372, 8826.352094371663, 8911.15344042315, 8911.955440222757, 8927.642894284156, 5810.564207684441, 5909.6158749681135, 5912.704364781744, 5915.970420147872, 5978.282606087298, 1735.8958462492292, 1758.2175931710522, 1762.4536393717988, 1767.407918347368, 1769.5841477252836, 925.6528801699362, 938.7653086728318, 951.3150531617224, 958.7603099225194, 963.7783309838776, 15546.533603991898, 19647.803521964597, 20241.493962651053, 20302.79697203009, 20567.074481648062, 815.0341844913796, 998.7103739915489, 1006.9848952265722, 1013.807376598444, 1016.4511163971521, 10315.896841031643, 11134.3152341174, 11183.329000259848, 11184.496697648625, 11276.1542884284, 536352.0, 581596.0, 581659.0, 613869.0, 617222.0, 57056.42943570511, 71186.0, 78339.60830195813, 89154.55422722845, 95444.0, 1037.4605205293205, 1045.7856139491387, 1057.0402871958486, 1060.9363438193734, 1062.0547712535695, 17414.868033577775, 17530.56173595619, 17951.281948722426, 18115.728264075788, 18145.727814082536, 31135.688643114187, 44213.77893110514, 46236.7688161557, 46321.073578528914, 46857.0, 60583.69708151431, 61545.76908461716, 61572.69213653903, 61664.76670466655, 61862.0, 7451.3293803557945, 7627.923720762943, 7669.769906902802, 7821.530708157528, 7998.440109192301, 12083.093767967262, 12499.56251531192, 12657.430415631503, 12824.487020519075, 12929.482820687068, 5099.592032637454, 7694.846103078019, 9122.771930701785, 10454.529546170288, 11049.834252486058, 10417.405640346593, 10615.628453004108, 10841.123362096529, 11017.394043327695, 11070.667879963614, 5851.297844258716, 7137.643117843983, 10915.290506117077, 11142.944285278521, 11255.212135150461, 7439.851202976019, 7571.432142589438, 7639.465237433326, 7643.1974642663945, 7665.616719163908, 10737.03366697001, 11011.669649910515, 11012.614558490415, 11020.0, 11107.444627768418], "unit": "score", "name": "jquery.html", "value": 9139.53743782467}, {"lowerIsBetter": false, "replicates": [1422.0401229170307, 1539.4842727686225, 1558.2053152892024, 1794.237449084139, 1938.476611314945, 7980.042394912604, 8663.350248731347, 8834.0, 8837.558122093902, 8853.114688531145, 8476.618552165151, 8710.651573937035, 8752.956235218815, 8754.255888249507, 8774.429662071962, 2196.198387588535, 2414.7947424468853, 2454.4845582427665, 2474.0969546115716, 2476.4675594747127, 2184.6722991551237, 2422.2127808462233, 2488.983923022349, 2502.8248022638422, 2506.4485813121173, 2395.8442701224367, 2416.214730212679, 2463.408781892351, 2469.938251543708, 2474.134053081425, 2426.733059363469, 2462.5567397868376, 2465.050955382173, 2474.826762126652, 2480.1567467061104, 2420.4674971506242, 2462.322861213163, 2470.629405589176, 2472.295395812185, 2475.9752402475924, 8760.8685869712, 8822.911770882338, 8854.734357969206, 8858.86711699325, 8872.911270887273, 4673.836415725434, 5397.0, 5531.944680553223, 5556.416576259475, 5578.414266501999, 3507.684308412255, 3635.327464419091, 3687.907802304937, 3696.131409118855, 3699.926001479955, 1049.9947500262526, 1061.0821639281965, 1072.774717309366, 1075.9623413180502, 1081.7403823082482, 12065.457054432572, 12133.090018248668, 12152.392380380945, 12193.024558035337, 12221.083418743541, 4867.951320486821, 4911.926321105187, 4932.605391568666, 4976.477469865612, 4983.053219888282, 10248.23138264633, 10256.897431025594, 10264.486775661187, 10271.9486402569, 10423.895761042444, 5453.400125986218, 5522.337319521683, 5558.916616250759, 5559.58303127259, 5567.638103523258, 4445.755483448442, 4602.723836569817, 4827.493113223061, 4889.095517329305, 4944.975275123601, 3827.75119617224, 3921.8235179416993, 3953.6244056814558, 3956.149427873021, 3974.4833171687496, 1271.2880786759445, 1277.2847205564913, 1286.292539103508, 1291.1607455154037, 1298.889594384469, 1115.3140818396628, 1314.664760486089, 1364.7065880835469, 1396.8253968253969, 1396.8393634731933, 8206.179382061866, 8316.334693224528, 8378.748637540763, 8400.663973441116, 8405.949256342958, 518.3080587415767, 519.6648161935606, 532.3611666000814, 534.8395481355577, 536.7557761218636, 6164.753409863555, 6193.473554747807, 6221.782237621662, 6230.7819226327765, 6231.563790534619, 10381.273310868317, 10774.299670521392, 10895.073918716998, 10899.509522071527, 10902.855200204023, 10425.957404259514, 10673.092787113183, 10730.946345268223, 10764.461776911123, 10766.623168189233, 8279.296259817984, 8376.748697539084, 8411.789705257417, 8444.66221351139, 8446.113158118309, 7615.276548727862, 7789.4157938155995, 7979.0, 7996.56018918965, 8004.159563245776, 8141.470804397695, 9288.838895138108, 9608.95195524018, 9663.613455461984, 9761.707148785548, 9745.512724363894, 9945.905950345312, 9974.251931105342, 10027.949860250652, 10081.848772268568, 6758.425533829482, 6854.9657251713425, 7359.705611775683, 7583.620818959141, 7653.732119375795, 8112.878306825521, 8197.426180167331, 8234.505929644241, 8240.340772738047, 8418.284445822173, 7196.0645116134565, 7275.272472752682, 7297.671604777692, 7397.334239918433, 7452.962735186289, 7099.574025558483, 7159.892601610876, 7163.498555101093, 7184.712611495691, 7217.711291548279, 6890.10428644273, 7675.61621918913, 7676.0, 7688.115866675404, 7703.229677032252, 5814.244148260699, 6384.0, 6575.421898744171, 6992.16094068715, 7004.649767511706, 6456.838579035561, 6621.370969757865, 6630.569013014138, 6642.734290628514, 6645.202575690754], "unit": "score", "name": "mootools.html", "value": 4829.010831203902}, {"lowerIsBetter": false, "replicates": [326.1015901192215, 327.6772379206481, 330.36240056690576, 334.7338865601846, 337.11171064245707, 6381.553291269613, 6423.389777971097, 6429.742810287588, 6469.70886310116, 6478.449331806791, 6391.488680905516, 6401.295857455676, 6413.743450261995, 6423.807285781422, 6434.935650643492, 4528.43394575678, 4654.4647365553055, 4659.906801863961, 4746.549077837617, 4761.2143996240575, 4720.669553131283, 4733.313669517918, 4733.81064757411, 4747.382840230765, 4750.643701722367, 4671.743054132022, 4674.696144750597, 4676.158291507527, 4689.109069276827, 4706.1999460091865, 4523.8642840714665, 4566.063956888863, 4683.2038553446, 4695.1548721230165, 4696.812127514895, 4047.514298284225, 4049.230646177217, 4054.1688953764406, 4057.5536690963972, 4073.3279008963486, 6064.241969753781, 6133.69331533426, 6198.721057552421, 6205.007198848208, 6210.937890621081, 4712.421338851489, 5489.039418101856, 5604.355499117612, 5609.887802243973, 5614.494695477386, 6888.793336199922, 6892.758753443605, 6892.827679308008, 6904.758333458305, 6905.75829845958, 4470.41884555009, 4619.699719518254, 4646.326282689025, 4653.53464653532, 4679.110968915929, 5846.883062338772, 7113.857722845514, 7155.856882862366, 7209.279072092853, 7215.603141827198, 3978.9455794214555, 4229.386738922839, 4715.504871988392, 4722.008378240539, 4743.169945259635, 4781.641376896677, 4802.255650374198, 4803.5916947059895, 4803.639727020487, 4810.903781924412, 5641.210230567724, 5651.406602306699, 5656.519195868315, 5666.660000399989, 5682.204491371212, 6647.734090636321, 6899.103116594811, 6902.240753517109, 6924.376806087476, 6938.098047253926, 1449.6665766873678, 1658.635100277937, 1659.3860271699548, 1672.9711227595185, 1676.8407001334854, 1074.3661239868359, 1081.691717860406, 1103.1175059952006, 1104.1056744037355, 1104.7238190452388, 5039.949600504021, 6106.145139680449, 6283.842903927347, 6311.274203466568, 6330.905036424458, 7190.784276471714, 7198.172210195789, 7235.421166306788, 7236.457265705093, 7270.382017528464, 6303.369663033752, 6310.4005119514495, 6320.4311611955145, 6324.2410910691015, 6334.429901308904, 3766.962330376716, 3813.790241536742, 3819.980900095482, 3825.0437390652337, 3832.96509942314, 7118.5017048806085, 7371.262873712693, 7379.963100184572, 7410.18487966334, 7423.035005449257, 5851.502622277069, 7337.816554586071, 7361.6319184040585, 7370.742024029133, 7372.96313518429, 7427.294407031323, 7480.812979675552, 7484.251574842581, 7486.5508069516, 7489.550626962399, 7097.432205423657, 7186.0, 7215.639218039182, 7240.782776516713, 7247.275272472711, 6852.691628876612, 7130.609531141377, 7151.104957186454, 7157.105361829771, 7172.784816455514, 7437.51656142349, 7482.95238666598, 7483.925160748323, 7484.663190156347, 7497.737579184703, 4491.3487544305235, 4558.407407037064, 4561.407017087758, 4562.977185114053, 4566.018306064212, 7171.211166771755, 7171.928280717335, 7172.354488096096, 7176.995220669006, 7214.386777124003, 6742.542324696864, 6924.169099708067, 6941.444684425335, 6944.791656250321, 6956.0, 6840.31596840312, 6903.827404314932, 6911.481638877205, 6915.965420172867, 6928.930710692828, 5505.9724701376235, 6771.187457505131, 6778.4916131289365, 6821.079154314104, 6824.488163387667, 5956.344802071855, 6617.338266173344, 6907.102076729993, 6941.958706194111, 6945.444364450731, 6520.608763474207, 6555.3772391622715, 6645.468362531083, 6686.063951046955, 6777.542828291941], "unit": "score", "name": "prototype.html", "value": 5045.834283933733}, {"lowerIsBetter": false, "replicates": [680.2924958043635, 717.2791344698577, 726.5531698005728, 741.9220981796909, 772.4322622872188, 14452.78320825187, 14646.633834154152, 14662.926685366585, 14686.118832870023, 14692.853071469282, 13174.802377964339, 13342.332883355843, 13531.729365412686, 13544.932275338635, 13547.119437236574, 712.3553184368155, 899.177252813678, 900.6532484993292, 902.3187493442442, 903.6701603914589, 888.9422187557788, 891.8840550728396, 904.6562306323588, 908.7001289574424, 909.7498187998287, 950.021477877787, 959.501059449086, 960.903909609037, 962.4033099478333, 962.7737481691797, 944.9558238146852, 952.538019060755, 954.0840792838883, 955.1833182628891, 956.9951550871592, 706.0151089230546, 709.2800807180715, 711.008143639625, 712.8182313510074, 714.039616715522, 12729.809052864215, 12843.743125137538, 12850.742985140338, 12884.935575322063, 12909.160904541175, 11050.392228427434, 11070.723231919186, 11274.718132046764, 11474.254173478697, 11514.827277590843, 9265.536723163883, 9392.812143757155, 9434.05659434051, 9443.575039123187, 9670.468124253162, 3401.608814986259, 3402.4896265560114, 3406.744494162948, 3418.6410426905068, 3503.562054743157, 67988.30029249258, 68722.65638671824, 74604.0, 77985.83021254743, 78468.0, 9575.616975321049, 9651.0, 9883.308168428284, 9942.850857237147, 10342.224333175041, 17055.744163837553, 20203.494912627302, 20695.79304206939, 20741.58516829655, 21144.0, 7454.40364770817, 7467.215942326086, 7547.547147171077, 7589.051368578927, 7655.961720191363, 4050.635442810102, 4051.4530538376944, 4077.7757223352405, 4106.2198182345255, 4327.653787696977, 3532.6643968822923, 3581.230035542371, 3584.766990145632, 3591.8204089795404, 3596.5504311961004, 634.1724050114552, 657.6448717692392, 659.4625380315028, 668.1314291421133, 669.10340144207, 527.2060059941218, 577.0910815465659, 590.852286928268, 596.9701514924324, 605.5700452678559, 8093.312068474128, 8200.548969806718, 8295.087540370554, 8340.833183336299, 8360.247577718035, 500.5395036566368, 576.0696475192534, 582.8775957048982, 596.0373995996434, 598.2855101797805, 3625.3655610268097, 3824.9426258606145, 3876.534815822063, 3912.550056743511, 4002.239574480839, 59840.50398740066, 76578.61710691522, 80609.59695201604, 80798.59600701959, 80853.0, 74842.0, 75527.24472755312, 75565.62217188989, 75660.62169689116, 76151.85772213421, 66902.0, 67241.66379168072, 67247.66376168186, 67540.6622966882, 68204.65897670579, 7582.128055273714, 7679.347255483346, 7821.960890195513, 7891.210878912063, 8019.557962282462, 49143.508564915326, 49338.75330623468, 49971.500284996684, 51621.0, 52035.479645203064, 58043.70978145082, 58361.4163858356, 66773.32106807585, 67165.32834671786, 68844.74333736501, 10994.395308258123, 11020.834687479533, 11130.944345278222, 11134.3876086816, 11294.774104518028, 11764.588239411922, 11934.701632458911, 11949.283043017447, 12155.392230388623, 12435.0, 11293.548258069914, 11321.433928303717, 11334.829977550178, 11433.542658293574, 11728.765424691288, 11405.828912566152, 11696.766064678828, 11794.646160615195, 11833.881661183623, 12005.039596832406, 11312.83030754523, 11332.0, 11417.200795944203, 11509.53961841517, 11870.109741769575, 9508.857367139637, 9562.378445401027, 9850.012981566337, 9904.0, 10096.444695541815, 9730.36752611078, 9753.366031207948, 9777.511124443607, 9786.363886347364, 9808.264380171373], "unit": "score", "name": "yui.html", "value": 6753.284177417929}], "name": "dromaeo_css", "value": 7375.970712964074}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 108.0, 110.0, 110.0, 112.0, 111.0, 116.0, 110.0, 111.0, 113.0], "unit": "score", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [158.0, 124.0, 151.0, 191.0, 150.0, 141.0, 142.0, 118.0, 164.0, 120.0], "unit": "score", "name": "audio-beat-detection", "value": 145.9}, {"lowerIsBetter": true, "replicates": [187.0, 184.0, 184.0, 185.0, 192.0, 263.0, 218.0, 233.0, 249.0, 182.0], "unit": "score", "name": "audio-dft", "value": 207.7}, {"lowerIsBetter": true, "replicates": [74.0, 80.0, 84.0, 80.0, 61.0, 59.0, 58.0, 79.0, 79.0, 55.0], "unit": "score", "name": "audio-fft", "value": 70.9}, {"lowerIsBetter": true, "replicates": [92.0, 102.0, 168.0, 117.0, 99.0, 79.0, 120.0, 81.0, 78.0, 122.0], "unit": "score", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [90.0, 84.0, 88.0, 85.0, 87.0, 84.0, 131.0, 83.0, 86.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 90.2}, {"lowerIsBetter": true, "replicates": [93.0, 101.0, 93.0, 93.0, 92.0, 92.0, 91.0, 93.0, 93.0, 110.0], "unit": "score", "name": "imaging-darkroom", "value": 95.1}, {"lowerIsBetter": true, "replicates": [85.0, 87.0, 84.0, 87.0, 84.0, 85.0, 84.0, 87.0, 86.0, 114.0], "unit": "score", "name": "imaging-desaturate", "value": 88.3}, {"lowerIsBetter": true, "replicates": [55.0, 54.0, 54.0, 53.0, 53.0, 53.0, 52.0, 66.0, 55.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 54.6}, {"lowerIsBetter": true, "replicates": [64.0, 72.0, 70.0, 76.0, 71.0, 69.0, 68.0, 70.0, 70.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.9}, {"lowerIsBetter": true, "replicates": [82.0, 84.0, 83.0, 84.0, 84.0, 82.0, 84.0, 81.0, 83.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.0}, {"lowerIsBetter": true, "replicates": [122.0, 137.0, 122.0, 122.0, 125.0, 122.0, 124.0, 130.0, 135.0, 138.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 127.7}, {"lowerIsBetter": true, "replicates": [140.0, 128.0, 142.0, 137.0, 138.0, 130.0, 135.0, 140.0, 137.0, 138.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 136.5}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 66.0, 66.0, 67.0, 67.0, 66.0, 68.0, 67.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.9}], "name": "kraken", "value": 1453.7000000000003}]} 09:52:17 INFO - Return code: 0 09:52:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:52:17 ERROR - # TBPL SUCCESS # 09:52:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:52:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:52:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:52:17 INFO - 2016-04-07 09:52:17.243 screenresolution[7503:167420] starting screenresolution argv=screenresolution get 09:52:17 INFO - 2016-04-07 09:52:17.248 screenresolution[7503:167420] Display 0: 1600x1200x32@60 09:52:17 INFO - 2016-04-07 09:52:17.254 screenresolution[7504:167423] starting screenresolution argv=screenresolution list 09:52:17 INFO - Available Modes on Display 0 09:52:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:52:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:52:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:52:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:52:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:52:17 INFO - Intel Iris: 09:52:17 INFO - Chipset Model: Intel Iris 09:52:17 INFO - Type: GPU 09:52:17 INFO - Bus: Built-In 09:52:17 INFO - VRAM (Dynamic, Max): 1536 MB 09:52:17 INFO - Vendor: Intel (0x8086) 09:52:17 INFO - Device ID: 0x0a2e 09:52:17 INFO - Revision ID: 0x0009 09:52:17 INFO - Displays: 09:52:17 INFO - Display: 09:52:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:52:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:52:17 INFO - Display Serial Number: 1600x1200 60 09:52:17 INFO - Main Display: Yes 09:52:17 INFO - Mirror: Off 09:52:17 INFO - Online: Yes 09:52:17 INFO - Rotation: Supported 09:52:17 INFO - Return code: 0 09:52:17 INFO - Running post-action listener: _resource_record_post_action 09:52:17 INFO - Running post-run listener: _resource_record_post_run 09:52:18 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 12288; Write bytes: 692668416; Read time: 0; Write time: 1976 09:52:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 383750656; Write bytes: 378238464; Read time: 28416; Write time: 832 09:52:18 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 12288; Write bytes: 296964608; Read time: 0; Write time: 1092 09:52:18 INFO - Running post-run listener: _upload_blobber_files 09:52:18 INFO - Blob upload gear active. 09:52:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:52:18 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:52:18 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:52:18 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:52:18 INFO - (blobuploader) - INFO - Open directory for files ... 09:52:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:52:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:52:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:19 INFO - (blobuploader) - INFO - Done attempting. 09:52:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:52:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:20 INFO - (blobuploader) - INFO - Done attempting. 09:52:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:52:20 INFO - Return code: 0 09:52:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:52:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:52:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c5b524de227b5dec575868d241ee8d7bce1be990d37d6ebed5a06c781c2b730315d3cee25bd1299e310e7939a9d49d44d1022d2e78e28dba3d58fb142573668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c60b77c56e0cb8d6a42af26a7fe3fa45db7bf8a77b890a9ca6c58895dfc9aaa4e024ea31dde24f7d540c8d2d13a7966ae839ff163410ff406cf66373646146a6"} 09:52:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:52:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:52:20 INFO - Contents: 09:52:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c5b524de227b5dec575868d241ee8d7bce1be990d37d6ebed5a06c781c2b730315d3cee25bd1299e310e7939a9d49d44d1022d2e78e28dba3d58fb142573668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c60b77c56e0cb8d6a42af26a7fe3fa45db7bf8a77b890a9ca6c58895dfc9aaa4e024ea31dde24f7d540c8d2d13a7966ae839ff163410ff406cf66373646146a6"} 09:52:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:52:20 INFO - Copying logs to upload dir... 09:52:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:52:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.986629 ========= master_lag: 10.43 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-07 09:52:30.382456) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 11 secs) (at 2016-04-07 09:52:30.389121) ========= 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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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/8c5b524de227b5dec575868d241ee8d7bce1be990d37d6ebed5a06c781c2b730315d3cee25bd1299e310e7939a9d49d44d1022d2e78e28dba3d58fb142573668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c60b77c56e0cb8d6a42af26a7fe3fa45db7bf8a77b890a9ca6c58895dfc9aaa4e024ea31dde24f7d540c8d2d13a7966ae839ff163410ff406cf66373646146a6"} 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.011914 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/8c5b524de227b5dec575868d241ee8d7bce1be990d37d6ebed5a06c781c2b730315d3cee25bd1299e310e7939a9d49d44d1022d2e78e28dba3d58fb142573668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c60b77c56e0cb8d6a42af26a7fe3fa45db7bf8a77b890a9ca6c58895dfc9aaa4e024ea31dde24f7d540c8d2d13a7966ae839ff163410ff406cf66373646146a6"}' ========= master_lag: 11.41 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 11 secs) (at 2016-04-07 09:52:41.806400) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2016-04-07 09:52:41.813378) ========= 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.TNCIERmEZ2/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.dK1pcZj5hR/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.005374 ========= master_lag: 10.39 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2016-04-07 09:52:52.210397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:52:52.210727) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:52:52.570593) ========= ========= Total master_lag: 90.12 =========