builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0077 starttime: 1460029336.72 results: success (0) buildid: 20160407043445 builduid: 8aad35983ab14a3881cbc82acebeabda revision: 68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.719945) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.720498) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.720820) ========= 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.gA5on8CTNz/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.bJ688Wqvol/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.005030 basedir: '/builds/slave/test' ========= master_lag: 0.07 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.796651) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.797217) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.840996) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.841374) ========= 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.gA5on8CTNz/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.bJ688Wqvol/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005564 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.898139) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.898503) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.901926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:16.902266) ========= 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.gA5on8CTNz/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.bJ688Wqvol/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-07 04:42:16-- 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% 34.2M=0s 2016-04-07 04:42:17 (34.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168154 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:17.087196) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:17.087514) ========= 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.gA5on8CTNz/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.bJ688Wqvol/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026803 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:17.138103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-07 04:42:17.138443) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gA5on8CTNz/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.bJ688Wqvol/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-07 04:42:17,218 truncating revision to first 12 chars 2016-04-07 04:42:17,218 Setting DEBUG logging. 2016-04-07 04:42:17,218 attempt 1/10 2016-04-07 04:42:17,218 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/68c0b7d6f16c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-07 04:42:18,364 unpacking tar archive at: mozilla-central-68c0b7d6f16c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.388521 ========= master_lag: 3.48 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-07 04:42:22.003950) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:22.004481) ========= echo 68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gA5on8CTNz/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.bJ688Wqvol/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8 program finished with exit code 0 elapsedTime=0.006110 script_repo_revision: '68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8' ========= master_lag: 0.70 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 04:42:22.714910) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-04-07 04:42:22.715239) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-04-07 04:42:27.400125) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-04-07 04:42:27.400378) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gA5on8CTNz/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.bJ688Wqvol/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:42:27 INFO - MultiFileLogger online at 20160407 04:42:27 in /builds/slave/test 04:42:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:42:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:42:27 INFO - {'append_to_log': False, 04:42:27 INFO - 'base_work_dir': '/builds/slave/test', 04:42:27 INFO - 'blob_upload_branch': 'Firefox', 04:42:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:42:27 INFO - 'branch': 'Firefox', 04:42:27 INFO - 'buildbot_json_path': 'buildprops.json', 04:42:27 INFO - 'config_files': ('talos/mac_config.py',), 04:42:27 INFO - 'default_actions': ('clobber', 04:42:27 INFO - 'read-buildbot-config', 04:42:27 INFO - 'download-and-extract', 04:42:27 INFO - 'populate-webroot', 04:42:27 INFO - 'create-virtualenv', 04:42:27 INFO - 'install', 04:42:27 INFO - 'run-tests'), 04:42:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:42:27 INFO - 'download_minidump_stackwalk': True, 04:42:27 INFO - 'download_symbols': 'ondemand', 04:42:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:42:27 INFO - 'tooltool.py': '/tools/tooltool.py', 04:42:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:42:27 INFO - '/tools/misc-python/virtualenv.py')}, 04:42:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:42:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:42:27 INFO - 'installer_path': 'installer.exe', 04:42:27 INFO - 'log_level': 'info', 04:42:27 INFO - 'log_name': 'talos', 04:42:27 INFO - 'log_to_console': True, 04:42:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:42:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:42:27 INFO - 'opt_config_files': (), 04:42:27 INFO - 'pip_index': False, 04:42:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:27 INFO - 'cmd': ('bash', 04:42:27 INFO - '-c', 04:42:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:27 INFO - 'enabled': True, 04:42:27 INFO - 'halt_on_failure': False, 04:42:27 INFO - 'name': 'check_screen_resolution'},), 04:42:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:27 INFO - 'cmd': ('bash', 04:42:27 INFO - '-c', 04:42:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:27 INFO - 'enabled': True, 04:42:27 INFO - 'halt_on_failure': False, 04:42:27 INFO - 'name': 'check_screen_resolution'},), 04:42:27 INFO - 'run_cmd_checks_enabled': True, 04:42:27 INFO - 'sps_profile': False, 04:42:27 INFO - 'sps_profile_interval': 0, 04:42:27 INFO - 'suite': 'dromaeojs', 04:42:27 INFO - 'system_bits': '32', 04:42:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:42:27 INFO - 'title': 't-yosemite-r7-0077', 04:42:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:42:27 INFO - 'use_talos_json': True, 04:42:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:42:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:42:27 INFO - 'work_dir': 'build'} 04:42:27 INFO - ##### 04:42:27 INFO - ##### Running clobber step. 04:42:27 INFO - ##### 04:42:27 INFO - Running pre-action listener: _resource_record_pre_action 04:42:27 INFO - Running main action method: clobber 04:42:27 INFO - rmtree: /builds/slave/test/build 04:42:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:42:29 INFO - Running post-action listener: _resource_record_post_action 04:42:29 INFO - ##### 04:42:29 INFO - ##### Running read-buildbot-config step. 04:42:29 INFO - ##### 04:42:29 INFO - Running pre-action listener: _resource_record_pre_action 04:42:29 INFO - Running main action method: read_buildbot_config 04:42:29 INFO - Using buildbot properties: 04:42:29 INFO - { 04:42:29 INFO - "project": "", 04:42:29 INFO - "product": "firefox", 04:42:29 INFO - "script_repo_revision": "production", 04:42:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:42:29 INFO - "repository": "", 04:42:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:42:29 INFO - "buildid": "20160407043445", 04:42:29 INFO - "stage_platform": "macosx64", 04:42:29 INFO - "basedir": "/builds/slave/test", 04:42:29 INFO - "buildnumber": 209, 04:42:29 INFO - "platform": "yosemite_r7", 04:42:29 INFO - "mozci_request": { 04:42:29 INFO - "priority": -1, 04:42:29 INFO - "type": "trigger_all_talos_jobs", 04:42:29 INFO - "times": 6 04:42:29 INFO - }, 04:42:29 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:42:29 INFO - "slavebuilddir": "test", 04:42:29 INFO - "branch": "mozilla-central", 04:42:29 INFO - "revision": "68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8", 04:42:29 INFO - "slavename": "t-yosemite-r7-0077", 04:42:29 INFO - "builduid": "8aad35983ab14a3881cbc82acebeabda", 04:42:29 INFO - "repo_path": "mozilla-central" 04:42:29 INFO - } 04:42:29 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.dmg. 04:42:29 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.test_packages.json. 04:42:29 INFO - Running post-action listener: _resource_record_post_action 04:42:29 INFO - ##### 04:42:29 INFO - ##### Running download-and-extract step. 04:42:29 INFO - ##### 04:42:29 INFO - Running pre-action listener: _resource_record_pre_action 04:42:29 INFO - Running main action method: download_and_extract 04:42:29 INFO - mkdir: /builds/slave/test/build/tests 04:42:29 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')]} 04:42:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.test_packages.json 04:42:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:42:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/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 04:42:29 INFO - Downloaded 1199 bytes. 04:42:29 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:42:29 INFO - Using the following test package requirements: 04:42:29 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:42:29 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:29 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:42:29 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:29 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:42:29 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:29 INFO - u'jsshell-mac.zip'], 04:42:29 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:29 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:42:29 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:42:29 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:29 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:42:29 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:29 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:42:29 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:29 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:42:29 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:29 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:42:29 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:42:29 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')]} 04:42:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/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 04:42:29 INFO - Downloaded 17964225 bytes. 04:42:29 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'] 04:42:29 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 04:42:32 INFO - Return code: 0 04:42:32 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 04:42:32 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')]} 04:42:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/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 04:42:33 INFO - Downloaded 17964225 bytes. 04:42:33 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'] 04:42:33 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 04:42:36 INFO - Return code: 0 04:42:36 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')]} 04:42:36 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.talos.tests.zip 04:42:36 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:42:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/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 04:42:36 INFO - Downloaded 10847617 bytes. 04:42:36 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'] 04:42:36 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 04:42:37 INFO - Return code: 0 04:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.dmg 04:42:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:42:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:42:38 INFO - Downloaded 108925595 bytes. 04:42:38 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.dmg 04:42:38 INFO - mkdir: /builds/slave/test/properties 04:42:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:42:38 INFO - Writing to file /builds/slave/test/properties/build_url 04:42:38 INFO - Contents: 04:42:38 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.dmg 04:42:38 INFO - Running post-action listener: _resource_record_post_action 04:42:38 INFO - Running post-action listener: set_extra_try_arguments 04:42:38 INFO - ##### 04:42:38 INFO - ##### Running populate-webroot step. 04:42:38 INFO - ##### 04:42:38 INFO - Running pre-action listener: _resource_record_pre_action 04:42:38 INFO - Running main action method: populate_webroot 04:42:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:42:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:42:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:42:38 INFO - '--tppagecycles', 04:42:38 INFO - '7'], 04:42:38 INFO - 'tests': ['tsvgm']}}, 04:42:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:42:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:42:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:42:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:42:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:38 INFO - 'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:42:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:42:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:38 INFO - 'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['damp', 'tps']}, 04:42:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:38 INFO - 'tests': ['damp', 'tps']}, 04:42:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['dromaeo_dom']}, 04:42:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:42:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['a11yr', 04:42:38 INFO - 'ts_paint', 04:42:38 INFO - 'tpaint', 04:42:38 INFO - 'sessionrestore', 04:42:38 INFO - 'sessionrestore_no_auto_restore', 04:42:38 INFO - 'tabpaint']}, 04:42:38 INFO - 'other-e10s': {'tests': ['a11yr', 04:42:38 INFO - 'ts_paint', 04:42:38 INFO - 'tpaint', 04:42:38 INFO - 'sessionrestore', 04:42:38 INFO - 'sessionrestore_no_auto_restore', 04:42:38 INFO - 'tabpaint']}, 04:42:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:42:38 INFO - 'ts_paint', 04:42:38 INFO - 'tpaint', 04:42:38 INFO - 'sessionrestore', 04:42:38 INFO - 'sessionrestore_no_auto_restore', 04:42:38 INFO - 'tabpaint']}, 04:42:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:42:38 INFO - 'ts_paint', 04:42:38 INFO - 'tpaint', 04:42:38 INFO - 'sessionrestore', 04:42:38 INFO - 'sessionrestore_no_auto_restore', 04:42:38 INFO - 'tabpaint']}, 04:42:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['a11yr', 04:42:38 INFO - 'ts_paint', 04:42:38 INFO - 'tpaint', 04:42:38 INFO - 'sessionrestore', 04:42:38 INFO - 'sessionrestore_no_auto_restore', 04:42:38 INFO - 'tabpaint']}, 04:42:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['a11yr', 04:42:38 INFO - 'ts_paint', 04:42:38 INFO - 'tpaint', 04:42:38 INFO - 'sessionrestore', 04:42:38 INFO - 'sessionrestore_no_auto_restore', 04:42:38 INFO - 'tabpaint']}, 04:42:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['tsvgx', 04:42:38 INFO - 'tsvgr_opacity', 04:42:38 INFO - 'tart', 04:42:38 INFO - 'tscrollx', 04:42:38 INFO - 'cart']}, 04:42:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:42:38 INFO - 'tsvgr_opacity', 04:42:38 INFO - 'tart', 04:42:38 INFO - 'tscrollx', 04:42:38 INFO - 'cart']}, 04:42:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:38 INFO - 'talos_options': ['--disable-e10s'], 04:42:38 INFO - 'tests': ['tp5o']}, 04:42:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:38 INFO - 'tests': ['tp5o']}, 04:42:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:38 INFO - 'talos_options': ['--disable-e10s', 04:42:38 INFO - '--xperf_path', 04:42:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:42:38 INFO - 'tests': ['tp5n']}, 04:42:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:38 INFO - 'talos_options': ['--xperf_path', 04:42:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:42:38 INFO - 'tests': ['tp5n']}}, 04:42:38 INFO - 'talos.zip': {'path': '', 04:42:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:42:38 INFO - Running post-action listener: _resource_record_post_action 04:42:38 INFO - ##### 04:42:38 INFO - ##### Running create-virtualenv step. 04:42:38 INFO - ##### 04:42:38 INFO - Running pre-action listener: _resource_record_pre_action 04:42:38 INFO - Running main action method: create_virtualenv 04:42:38 INFO - Creating virtualenv /builds/slave/test/build/venv 04:42:38 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 04:42:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:42:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:42:38 INFO - Using real prefix '/tools/python27' 04:42:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:42:39 INFO - Installing distribute.............................................................................................................................................................................................done. 04:42:42 INFO - Installing pip.................done. 04:42:42 INFO - Return code: 0 04:42:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68b58d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, '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.gA5on8CTNz/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.bJ688Wqvol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:42 INFO - 'HOME': '/Users/cltbld', 04:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:42 INFO - 'LOGNAME': 'cltbld', 04:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:42 INFO - 'MOZ_NO_REMOTE': '1', 04:42:42 INFO - 'NO_EM_RESTART': '1', 04:42:42 INFO - 'PAGER': '/bin/cat', 04:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:42 INFO - 'PWD': '/builds/slave/test', 04:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:42 INFO - 'SHELL': '/bin/bash', 04:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:42:42 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:42 INFO - 'USER': 'cltbld', 04:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:42 INFO - 'XPC_FLAGS': '0x0', 04:42:42 INFO - 'XPC_SERVICE_NAME': '0', 04:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:42 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 04:42:42 INFO - Cleaning up... 04:42:42 INFO - Return code: 0 04:42:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105b45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68b58d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, '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.gA5on8CTNz/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.bJ688Wqvol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:42 INFO - 'HOME': '/Users/cltbld', 04:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:42 INFO - 'LOGNAME': 'cltbld', 04:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:42 INFO - 'MOZ_NO_REMOTE': '1', 04:42:42 INFO - 'NO_EM_RESTART': '1', 04:42:42 INFO - 'PAGER': '/bin/cat', 04:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:42 INFO - 'PWD': '/builds/slave/test', 04:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:42 INFO - 'SHELL': '/bin/bash', 04:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:42:42 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:42 INFO - 'USER': 'cltbld', 04:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:42 INFO - 'XPC_FLAGS': '0x0', 04:42:42 INFO - 'XPC_SERVICE_NAME': '0', 04:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:42 INFO - Downloading/unpacking psutil>=0.7.1 04:42:42 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 04:42:42 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 04:42:42 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 04:42:42 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 04:42:42 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 04:42:42 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 04:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:42:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:42:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:42:45 INFO - Installing collected packages: psutil 04:42:45 INFO - Running setup.py install for psutil 04:42:45 INFO - building 'psutil._psutil_osx' extension 04:42: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 04:42:46 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 04:42:46 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 04:42:46 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 04:42:46 INFO - building 'psutil._psutil_posix' extension 04:42:46 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 04:42:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:42:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:42:46 INFO - ^ 04:42:46 INFO - 1 warning generated. 04:42: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 04:42:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:42:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:42:46 INFO - Successfully installed psutil 04:42:46 INFO - Cleaning up... 04:42:46 INFO - Return code: 0 04:42:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:42: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')]} 04:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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')]} 04:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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 0x105b45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68b58d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, '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.gA5on8CTNz/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.bJ688Wqvol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42: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 04:42: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 04:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:46 INFO - 'HOME': '/Users/cltbld', 04:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:46 INFO - 'LOGNAME': 'cltbld', 04:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:46 INFO - 'MOZ_NO_REMOTE': '1', 04:42:46 INFO - 'NO_EM_RESTART': '1', 04:42:46 INFO - 'PAGER': '/bin/cat', 04:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:46 INFO - 'PWD': '/builds/slave/test', 04:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:46 INFO - 'SHELL': '/bin/bash', 04:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:42:46 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:46 INFO - 'USER': 'cltbld', 04:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:46 INFO - 'XPC_FLAGS': '0x0', 04:42:46 INFO - 'XPC_SERVICE_NAME': '0', 04:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:42:47 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 04:42:47 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 04:42:47 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 04:42:47 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 04:42:47 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 04:42:47 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 04:42:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:42: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) 04:42:49 INFO - Installing collected packages: mozsystemmonitor 04:42:49 INFO - Running setup.py install for mozsystemmonitor 04:42:49 INFO - Successfully installed mozsystemmonitor 04:42:49 INFO - Cleaning up... 04:42:49 INFO - Return code: 0 04:42:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:42: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')]} 04:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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')]} 04:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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 0x105b45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68b58d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, '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.gA5on8CTNz/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.bJ688Wqvol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42: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 04:42: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 04:42:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:42:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:49 INFO - 'HOME': '/Users/cltbld', 04:42:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:49 INFO - 'LOGNAME': 'cltbld', 04:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:49 INFO - 'MOZ_NO_REMOTE': '1', 04:42:49 INFO - 'NO_EM_RESTART': '1', 04:42:49 INFO - 'PAGER': '/bin/cat', 04:42:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:49 INFO - 'PWD': '/builds/slave/test', 04:42:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:49 INFO - 'SHELL': '/bin/bash', 04:42:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:42:49 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:42:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:49 INFO - 'USER': 'cltbld', 04:42:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:49 INFO - 'XPC_FLAGS': '0x0', 04:42:49 INFO - 'XPC_SERVICE_NAME': '0', 04:42:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:49 INFO - Downloading/unpacking blobuploader==1.2.4 04:42: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 04:42: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 04:42:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:52 INFO - Downloading blobuploader-1.2.4.tar.gz 04:42:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:42:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:42:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:42:53 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 04:42:53 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 04:42:53 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 04:42:53 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 04:42:53 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 04:42:53 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 04:42:53 INFO - Downloading docopt-0.6.1.tar.gz 04:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:42:53 INFO - Installing collected packages: blobuploader, requests, docopt 04:42:53 INFO - Running setup.py install for blobuploader 04:42:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:42:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:42:53 INFO - Running setup.py install for requests 04:42:54 INFO - Running setup.py install for docopt 04:42:54 INFO - Successfully installed blobuploader requests docopt 04:42:54 INFO - Cleaning up... 04:42:54 INFO - Return code: 0 04:42:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42: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')]} 04:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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')]} 04:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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 0x105b45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68b58d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, '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.gA5on8CTNz/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.bJ688Wqvol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42: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 04:42: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 04:42:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:42:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:54 INFO - 'HOME': '/Users/cltbld', 04:42:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:54 INFO - 'LOGNAME': 'cltbld', 04:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:54 INFO - 'MOZ_NO_REMOTE': '1', 04:42:54 INFO - 'NO_EM_RESTART': '1', 04:42:54 INFO - 'PAGER': '/bin/cat', 04:42:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:54 INFO - 'PWD': '/builds/slave/test', 04:42:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:54 INFO - 'SHELL': '/bin/bash', 04:42:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:42:54 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:42:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:54 INFO - 'USER': 'cltbld', 04:42:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:54 INFO - 'XPC_FLAGS': '0x0', 04:42:54 INFO - 'XPC_SERVICE_NAME': '0', 04:42:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-3TtmH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-dhYNKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-y7Oy8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-INLOzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-UTBwbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-rkcDQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:42:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-7D1MCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:42:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-NqTcoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:42:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-NmW2wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:42:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-iQQNZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:42:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-nx4LAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:42:56 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-juPeId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:42:56 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-mLPiS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:42:56 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-DT47zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:56 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-U1P5t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:42:56 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-zQCiQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:42:56 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-CqtZEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:42:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:42:56 INFO - Running setup.py install for manifestparser 04:42:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:42:56 INFO - Running setup.py install for mozcrash 04:42:56 INFO - Running setup.py install for mozdebug 04:42:57 INFO - Running setup.py install for mozdevice 04:42:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:42:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:42:57 INFO - Running setup.py install for mozfile 04:42:57 INFO - Running setup.py install for mozhttpd 04:42:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:42:57 INFO - Running setup.py install for mozinfo 04:42:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:42:57 INFO - Running setup.py install for mozInstall 04:42:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:42:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:42:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:42:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:42:57 INFO - Running setup.py install for mozleak 04:42:57 INFO - Running setup.py install for mozlog 04:42:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:42:58 INFO - Running setup.py install for moznetwork 04:42:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:42:58 INFO - Running setup.py install for mozprocess 04:42:58 INFO - Running setup.py install for mozprofile 04:42:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:42:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:42:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:42:58 INFO - Running setup.py install for mozrunner 04:42:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:42:58 INFO - Running setup.py install for mozscreenshot 04:42:58 INFO - Running setup.py install for moztest 04:42:59 INFO - Running setup.py install for mozversion 04:42:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:42:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:42:59 INFO - Cleaning up... 04:42:59 INFO - Return code: 0 04:42:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:59 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 0x105b45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68b58d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, '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.gA5on8CTNz/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.bJ688Wqvol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:59 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 04:42:59 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 04:42:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:42:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:59 INFO - 'HOME': '/Users/cltbld', 04:42:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:59 INFO - 'LOGNAME': 'cltbld', 04:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:59 INFO - 'MOZ_NO_REMOTE': '1', 04:42:59 INFO - 'NO_EM_RESTART': '1', 04:42:59 INFO - 'PAGER': '/bin/cat', 04:42:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:59 INFO - 'PWD': '/builds/slave/test', 04:42:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:59 INFO - 'SHELL': '/bin/bash', 04:42:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:42:59 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:42:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:59 INFO - 'USER': 'cltbld', 04:42:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:59 INFO - 'XPC_FLAGS': '0x0', 04:42:59 INFO - 'XPC_SERVICE_NAME': '0', 04:42:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:59 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-dkDcX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42: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)) 04:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:59 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-jdr3BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42: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)) 04:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:59 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-2yqSdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42: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)) 04:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:59 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-RcEo6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42: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)) 04:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:59 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-AGYOSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42: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)) 04:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:59 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-l511wM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:43:00 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)) 04:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:43:00 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-TNLthq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:43:00 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)) 04:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:43:00 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Lq3Tqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:43:00 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)) 04:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:43:00 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-TEDw9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:43:00 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)) 04:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:43:00 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-DCzAqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:43: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)) 04:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:43:00 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-k54pQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:43: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)) 04:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:43:00 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-1NfujE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:43: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)) 04:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:43:00 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Nu3mFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:43: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)) 04:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:43:00 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Nt6HrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:43: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)) 04:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:00 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-mbQUcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:01 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)) 04:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:43:01 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-f_Fy9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:43:01 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)) 04:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:43:01 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-IEr4N2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:43:01 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)) 04:43:01 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)) 04:43:01 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)) 04:43:01 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)) 04:43:01 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)) 04:43:01 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)) 04:43:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:03 INFO - Downloading blessings-1.6.tar.gz 04:43:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:43:03 INFO - Installing collected packages: blessings 04:43:03 INFO - Running setup.py install for blessings 04:43:03 INFO - Successfully installed blessings 04:43:03 INFO - Cleaning up... 04:43:04 INFO - Return code: 0 04:43:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:43:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:43:04 INFO - Reading from file tmpfile_stdout 04:43:04 INFO - Current package versions: 04:43:04 INFO - blessings == 1.6 04:43:04 INFO - blobuploader == 1.2.4 04:43:04 INFO - docopt == 0.6.1 04:43:04 INFO - manifestparser == 1.1 04:43:04 INFO - mozInstall == 1.12 04:43:04 INFO - mozcrash == 0.17 04:43:04 INFO - mozdebug == 0.1 04:43:04 INFO - mozdevice == 0.48 04:43:04 INFO - mozfile == 1.2 04:43:04 INFO - mozhttpd == 0.7 04:43:04 INFO - mozinfo == 0.9 04:43:04 INFO - mozleak == 0.1 04:43:04 INFO - mozlog == 3.1 04:43:04 INFO - moznetwork == 0.27 04:43:04 INFO - mozprocess == 0.22 04:43:04 INFO - mozprofile == 0.28 04:43:04 INFO - mozrunner == 6.11 04:43:04 INFO - mozscreenshot == 0.1 04:43:04 INFO - mozsystemmonitor == 0.0 04:43:04 INFO - moztest == 0.7 04:43:04 INFO - mozversion == 1.4 04:43:04 INFO - psutil == 3.1.1 04:43:04 INFO - requests == 1.2.3 04:43:04 INFO - wsgiref == 0.1.2 04:43:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43: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')]} 04:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:04 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 0x105b45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68b58d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, '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.gA5on8CTNz/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.bJ688Wqvol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:43:04 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 04:43:04 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 04:43:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:43:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:04 INFO - 'HOME': '/Users/cltbld', 04:43:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:04 INFO - 'LOGNAME': 'cltbld', 04:43:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:04 INFO - 'MOZ_NO_REMOTE': '1', 04:43:04 INFO - 'NO_EM_RESTART': '1', 04:43:04 INFO - 'PAGER': '/bin/cat', 04:43:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:04 INFO - 'PWD': '/builds/slave/test', 04:43:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:04 INFO - 'SHELL': '/bin/bash', 04:43:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:43:04 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:43:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:04 INFO - 'USER': 'cltbld', 04:43:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:04 INFO - 'XPC_FLAGS': '0x0', 04:43:04 INFO - 'XPC_SERVICE_NAME': '0', 04:43:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43: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)) 04:43: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)) 04:43: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)) 04:43: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)) 04:43: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)) 04:43: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)) 04:43: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)) 04:43: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)) 04:43: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)) 04:43: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)) 04:43: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)) 04:43:04 INFO - Cleaning up... 04:43:04 INFO - Return code: 0 04:43:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:43: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')]} 04:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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 0x105b45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68b58d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, '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.gA5on8CTNz/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.bJ688Wqvol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:43: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 04:43: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 04:43:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:43:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:04 INFO - 'HOME': '/Users/cltbld', 04:43:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:04 INFO - 'LOGNAME': 'cltbld', 04:43:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:04 INFO - 'MOZ_NO_REMOTE': '1', 04:43:04 INFO - 'NO_EM_RESTART': '1', 04:43:04 INFO - 'PAGER': '/bin/cat', 04:43:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:04 INFO - 'PWD': '/builds/slave/test', 04:43:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:04 INFO - 'SHELL': '/bin/bash', 04:43:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:43:04 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:43:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:04 INFO - 'USER': 'cltbld', 04:43:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:04 INFO - 'XPC_FLAGS': '0x0', 04:43:04 INFO - 'XPC_SERVICE_NAME': '0', 04:43:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:04 INFO - Downloading/unpacking jsonschema 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:43:07 INFO - Downloading/unpacking functools32 (from jsonschema) 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:07 INFO - Downloading functools32-3.2.3-2.tar.gz 04:43:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:43:08 INFO - warning: no files found matching '*.txt' 04:43:08 INFO - no previously-included directories found matching 'build' 04:43:08 INFO - no previously-included directories found matching 'dist' 04:43:08 INFO - no previously-included directories found matching '.git*' 04:43:08 INFO - Installing collected packages: jsonschema, functools32 04:43:08 INFO - Running setup.py install for functools32 04:43:08 INFO - warning: no files found matching '*.txt' 04:43:08 INFO - no previously-included directories found matching 'build' 04:43:08 INFO - no previously-included directories found matching 'dist' 04:43:08 INFO - no previously-included directories found matching '.git*' 04:43:08 INFO - Successfully installed jsonschema functools32 04:43:08 INFO - Cleaning up... 04:43:08 INFO - Return code: 0 04:43:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:43:08 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')]} 04:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:08 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')]} 04:43:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:08 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 0x105b45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe68b58d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, '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.gA5on8CTNz/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.bJ688Wqvol/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:43:08 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 04:43:08 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 04:43:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:43:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:08 INFO - 'HOME': '/Users/cltbld', 04:43:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:08 INFO - 'LOGNAME': 'cltbld', 04:43:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:08 INFO - 'MOZ_NO_REMOTE': '1', 04:43:08 INFO - 'NO_EM_RESTART': '1', 04:43:08 INFO - 'PAGER': '/bin/cat', 04:43:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:08 INFO - 'PWD': '/builds/slave/test', 04:43:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:08 INFO - 'SHELL': '/bin/bash', 04:43:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:43:08 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:43:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:08 INFO - 'USER': 'cltbld', 04:43:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:08 INFO - 'XPC_FLAGS': '0x0', 04:43:08 INFO - 'XPC_SERVICE_NAME': '0', 04:43:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:08 INFO - Downloading/unpacking flake8 04:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:43:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:43:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:43:11 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 04:43:11 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 04:43:11 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 04:43:11 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 04:43:11 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 04:43:11 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 04:43:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:43:11 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 04:43:11 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 04:43:11 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 04:43:11 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 04:43:11 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 04:43:11 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 04:43:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:43:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:43:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:43:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:43:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:43:12 INFO - no previously-included directories found matching 'docs/_build' 04:43:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:43:12 INFO - Running setup.py install for pep8 04:43:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:43:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:43:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:43:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:43:12 INFO - no previously-included directories found matching 'docs/_build' 04:43:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:43:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:43:12 INFO - Cleaning up... 04:43:12 INFO - Return code: 0 04:43:12 INFO - Running post-action listener: _resource_record_post_action 04:43:12 INFO - Running post-action listener: _start_resource_monitoring 04:43:12 INFO - Starting resource monitoring. 04:43:12 INFO - ##### 04:43:12 INFO - ##### Running install step. 04:43:12 INFO - ##### 04:43:12 INFO - Running pre-action listener: _resource_record_pre_action 04:43:12 INFO - Running main action method: install 04:43:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:43:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:43:12 INFO - Reading from file tmpfile_stdout 04:43:12 INFO - Detecting whether we're running mozinstall >=1.0... 04:43:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:43:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:43:13 INFO - Reading from file tmpfile_stdout 04:43:13 INFO - Output received: 04:43:13 INFO - Usage: mozinstall [options] installer 04:43:13 INFO - Options: 04:43:13 INFO - -h, --help show this help message and exit 04:43:13 INFO - -d DEST, --destination=DEST 04:43:13 INFO - Directory to install application into. [default: 04:43:13 INFO - "/builds/slave/test"] 04:43:13 INFO - --app=APP Application being installed. [default: firefox] 04:43:13 INFO - mkdir: /builds/slave/test/build/application 04:43:13 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'] 04:43:13 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 04:43:46 INFO - Reading from file tmpfile_stdout 04:43:46 INFO - Output received: 04:43:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:43:46 INFO - Running post-action listener: _resource_record_post_action 04:43:46 INFO - ##### 04:43:46 INFO - ##### Running run-tests step. 04:43:46 INFO - ##### 04:43:46 INFO - Running pre-action listener: _resource_record_pre_action 04:43:46 INFO - Running main action method: run_tests 04:43:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:43:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:43:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:43:46 INFO - 2016-04-07 04:43:46.321 screenresolution[1641:7438] starting screenresolution argv=screenresolution get 04:43:46 INFO - 2016-04-07 04:43:46.323 screenresolution[1641:7438] Display 0: 1600x1200x32@60 04:43:46 INFO - 2016-04-07 04:43:46.329 screenresolution[1642:7440] starting screenresolution argv=screenresolution list 04:43:46 INFO - Available Modes on Display 0 04:43:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:43:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:43:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:43:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:43:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:43:46 INFO - Intel Iris: 04:43:46 INFO - Chipset Model: Intel Iris 04:43:46 INFO - Type: GPU 04:43:46 INFO - Bus: Built-In 04:43:46 INFO - VRAM (Dynamic, Max): 1536 MB 04:43:46 INFO - Vendor: Intel (0x8086) 04:43:46 INFO - Device ID: 0x0a2e 04:43:46 INFO - Revision ID: 0x0009 04:43:46 INFO - Displays: 04:43:46 INFO - Display: 04:43:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:43:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:43:46 INFO - Display Serial Number: 1600x1200 60 04:43:46 INFO - Main Display: Yes 04:43:46 INFO - Mirror: Off 04:43:46 INFO - Online: Yes 04:43:46 INFO - Rotation: Supported 04:43:46 INFO - Return code: 0 04:43:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:43:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:43:46 INFO - Python 2.7.3 04:43:46 INFO - Return code: 0 04:43:46 INFO - grabbing minidump binary from tooltool 04:43: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')]} 04:43:46 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 0x1059e4e00>, '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 0x10580ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059dfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:43:46 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 04:43:46 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 04:43:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:43:46 INFO - Return code: 0 04:43:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:43:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:43:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:43:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:43:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:43:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:43:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:43:47 INFO - Return code: 0 04:43:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:43:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gA5on8CTNz/Render', 04:43:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:47 INFO - 'HOME': '/Users/cltbld', 04:43:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:47 INFO - 'LOGNAME': 'cltbld', 04:43:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:43:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:47 INFO - 'MOZ_NO_REMOTE': '1', 04:43:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:43:47 INFO - 'NO_EM_RESTART': '1', 04:43:47 INFO - 'PAGER': '/bin/cat', 04:43:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:47 INFO - 'PWD': '/builds/slave/test', 04:43:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:43:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:47 INFO - 'SHELL': '/bin/bash', 04:43:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ688Wqvol/Listeners', 04:43:47 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 04:43:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:47 INFO - 'USER': 'cltbld', 04:43:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:47 INFO - 'XPC_FLAGS': '0x0', 04:43:47 INFO - 'XPC_SERVICE_NAME': '0', 04:43:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:43:48 INFO - mozversion application_buildid: 20160406030021 04:43:48 INFO - mozversion application_changeset: 68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8 04:43:48 INFO - mozversion application_display_name: Nightly 04:43:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:43:48 INFO - mozversion application_name: Firefox 04:43:48 INFO - mozversion application_remotingname: firefox 04:43:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:43:48 INFO - mozversion application_vendor: Mozilla 04:43:48 INFO - mozversion application_version: 48.0a1 04:43:48 INFO - mozversion platform_buildid: 20160406030021 04:43:48 INFO - mozversion platform_changeset: 68c0b7d6f16ce5bb023e08050102b5f2fe4aacd8 04:43:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:43:48 INFO - mozversion platform_version: 48.0a1 04:43:48 INFO - using testdate: 1460029428 04:43:48 INFO - actual date: 1460029428 04:43:48 INFO - starting webserver on 'localhost:49264' 04:43:48 INFO - SUITE-START | Running 2 tests 04:43:48 INFO - TEST-START | dromaeo_css 04:43:48 INFO - operating with platform_type : mac_ 04:43:48 INFO - Initialising browser for dromaeo_css test... 04:43:48 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpGfPKX6/profile http://localhost:49264/getInfo.html) 04:43:50 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 04:43:50 INFO - PROCESS | 1660 | colorDepth:24 04:43:50 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 04:43:50 INFO - PROCESS | 1660 | __metrics 04:43:50 INFO - PROCESS | 1660 | 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" 04:43:50 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:43:51 INFO - TEST-INFO | 1660: exit 0 04:43:51 INFO - Browser initialized. 04:43:51 INFO - Running cycle 1/1 for dromaeo_css test... 04:43:51 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpGfPKX6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:03 INFO - PROCESS | 1665 | RSS: Main: 236355584 04:44:03 INFO - PROCESS | 1665 | 04:44:53 INFO - PROCESS | 1665 | 1460029493996 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] 04:47:04 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49264/tests/dromaeo/cssquery-ext.html) 04:47:05 INFO - PROCESS | 1665 | RSS: Main: 345837568 04:47:05 INFO - PROCESS | 1665 | 04:50:07 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-ext.html (next: http://localhost:49264/tests/dromaeo/cssquery-jquery.html) 04:50:08 INFO - PROCESS | 1665 | RSS: Main: 367980544 04:50:08 INFO - PROCESS | 1665 | 04:53:10 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49264/tests/dromaeo/cssquery-mootools.html) 04:53:11 INFO - PROCESS | 1665 | RSS: Main: 363880448 04:53:11 INFO - PROCESS | 1665 | 04:56:13 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49264/tests/dromaeo/cssquery-prototype.html) 04:56:13 INFO - PROCESS | 1665 | RSS: Main: 326746112 04:56:13 INFO - PROCESS | 1665 | 04:59:16 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49264/tests/dromaeo/cssquery-yui.html) 04:59:17 INFO - PROCESS | 1665 | RSS: Main: 326623232 04:59:17 INFO - PROCESS | 1665 | 05:02:20 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-yui.html (next: http://localhost:49264/tests/dromaeo/cssquery-dojo.html) 05:02:20 INFO - PROCESS | 1665 | RSS: Main: 421826560 05:02:20 INFO - PROCESS | 1665 | 05:02:20 INFO - PROCESS | 1665 | __start_tp_report 05:02:20 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 05:02:20 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 05:02:20 INFO - PROCESS | 1665 | |i|pagename|runs| 05:02:20 INFO - PROCESS | 1665 | |0;dojo.html;2809.452156829418;3445.5865296164447;3478.721702263819;3511.6839484446396;3521.6830485256323;5586.217929489874;5674.205611214428;5680.715964201789;5681.289838770153;5687.544996400288;5683.971580142103;5688.288963879515;5731.713414329277;5845.532357411407;5852.297724273093;5092.439831618528;5113.386393632751;5116.718580478072;5137.2037334213255;5242.161254199329;5207.401148867872;5212.817551385702;5237.423883372845;5274.314339135907;5310.123829568117;5090.669106508084;5146.433892271847;5194.610404219679;5208.218767184915;5234.398044224925;5111.821086262;5165.1219292720325;5183.559397451183;5200.453952335026;5222.817201397933;5142;5156.407013193493;5162.199859021857;5205.27126202332;5211;5510.201020851954;5598.720063996825;5657.519110875581;5676.517496012844;5754.1944127822135;6246.406591373858;6267.435930766253;6269.153664255358;6271.372862713738;6296.338884417116;6252.937470625327;6313.242410910674;6326.557141000132;6326.620402775849;6329.8417539561415;5679.034564124109;5750.683712395817;5787.855303617401;5862.530997520188;5869.295684517885;6318.241810982711;6339.049142628643;6343.397377249154;6347.428731414195;6363.363663633692;6061.787837425757;6065.090236464565;6108.908366374508;6154.3845615438095;6172.321044685081;6125.601835880653;6192.256929168439;6203.720832562545;6210.50315974721;6228.065790131516;6101.186783078283;6104.822969073627;6216.2540495140875;6225.750969961151;6242.875142497124;6247.187865577536;6255.12428260044;6256.217972753406;6264.123022776815;6273.62358258506;4821.349117869113;4846.176150054499;4873.488283730228;4910.754462276872;4929.75351232437;4375.124975005012;4376.234159022159;4499.167653984024;4500.774961251925;4502.707324023928;5701;5707.771689132472;5707.800226992035;5709.942900570941;5713.628614140151;6230.376962303824;6278.277998030194;6304.716287767062;6314.242290925118;6316.62100273985;5553.694546799965;5634.070378387521;5669.489745922804;5713.971430142823;5746.712664366765;5277.973610132001;5332.253484512172;5332.9733351333;5336.973315133399;5337.066013447631;6293.968530157412;6297.307296197506;6311.147995020705;6321.905171422341;6332.366763323723;6143.815685529441;6208.9689551551955;6227.377262273736;6322.241331040304;6333.524985626004;6299.30707622152;6301.0863424804065;6303.306636269915;6312.2740884796995;6315.3053164152825;6145.508359331154;6168.722407491763;6184.752609895554;6192.628442293477;6215.844603884939;6122.785702500391;6154.676744499954;6233.376662333731;6239.032949892774;6276.623402595676;6068.332483426731;6078.088286757022;6102.365138425952;6104.938950610437;6108.350745298833;5123.436421993503;5919.792807251725;6112.533877023439;6123.571350005457;6125.84596676928;4915.950840491692;5765.827025189251;5878.147668588116;5969.492593129633;6002.609830361012;5048.066107770074;5557.666540007613;6070.939290607037;6101.694915254131;6112.541559382975;5173.379194496684;5375.354957405137;5721.027425337619;5955.5235581154275;5957.523398128053 05:02:20 INFO - PROCESS | 1665 | |1;ext.html;457.96794224404306;576.7513333632686;580.5297708855829;631.2929518938788;633.8922383194858;30734;31158;31197.68802311979;38955.41566876499;39574.01064973377;42231.9442013949;42576;42673.57326426735;42758;42818.35772463408;9460.8580871287;9678.128968392843;9683;9685;9688.46713430761;9466.62133514662;9646.37298575594;9649.03509649033;9653.082957119062;9653.806923861519;9602.183814375752;9683.418994860296;9692.321537492344;9779;9794.461304628243;9679.5644196011;9731.464769437678;9741.853872191887;9744.31789774716;9755.170810481117;7328.193898671142;7418.962905185494;7423.665935032936;7423.925760742378;7424;40989.385159222635;41323.58676413228;41395.793021035;41424;41691.58308416908;3219.7746157768966;3375.3080618473155;3388.6950174484414;3486;3868.458415821787;28260.15219543417;29737.70262297371;30264.092077237718;30416.391672166654;30485.085447436613;2411.915582954588;2686.6104414859738;2766.336079340944;2804.326961529239;2832.985835070832;490553;491322;492225;492962;493304;64904;65138.67430662816;65561.67219163873;65821;66736.33263667398;62992.37007629865;65250.673746630964;65412.345876540625;65438;65461;14027.509037183854;17186.31254749796;17217;17251.74122388165;17260.482185534453;12650.367481625883;12725.93637031809;12731.490740370467;12752;12759.234445933274;18771.86895970676;20502.487437813874;20542.69185962212;20572.38282851517;20581.58836823255;6724.856774348373;7682.654280557388;7693.346065584376;7697.23027697237;7819.569923654253;821.7411515372638;945.3524335829978;1007.2495990487134;1035.6168217759478;1058.692979036071;27606;27831.582526262122;28528.572071418945;28585.57121643177;28629.427411451656;1057.3972835483855;1148.7989601819647;1161.9070474362031;1190.7737529869298;1235.407004637761;14222.080019197483;14264.786028209584;14533;14704.26478676062;14980.850191498164;535885;536327;542386.2880685651;543589;544638;59304.11043834346;59689.50776230629;59710.70144649249;59773.103403448986;59841;1403.8175037245094;1435.3038776193537;1439.1509009684337;1455.6433673749787;1466.948656797007;28443;28763.06611566118;28802;29086.418271634026;29564;43131.353029703954;43546.564534355515;43742;43765.34351984786;43824.34263486114;373835;376354;376383;376895;377053;28335.57496637511;30456.238594035327;32333.706651733668;33712.15719607029;33739;41929.161416772105;42434.15131697411;42727.359089613055;42838;43458.13083738371;34281.97154085382;35419.82290088533;35482.645173547935;36356.81821590981;36442.635573644984;27837.582436263878;34812.9556113317;35290.64709352976;35440;35635.643643563235;35720.64279357173;36398.8180059098;36974;37413.81293093517;37431.43852842155;31455.37089258248;31585.89449369261;31864.522032169072;32176.678233217368;32223.67776322207;22531.324060278217;23640.645390318816;23702.762972370056;24119;24181.879090604434 05:02:20 INFO - PROCESS | 1665 | |2;jquery.html;361.9113317237276;364.6481145694405;366.073833201999;370.2243799240592;373.6711693709535;19296.51758706033;20047.799522004774;20405.693914591295;20949.05729242184;20972.790272097274;11985.64043078709;12344.25934443934;12357.19678220915;12364.196327238724;12386.81419778704;5068.518490743373;5141.485851414866;5282.274337473646;6030.819075427722;6040.275166979968;5812.534997200214;5910.527157827385;5917.20117784098;5947.078202878539;5947.4944629706315;5191.247269145972;5555.9721451531395;5669.177969194464;5780.421957804228;5883.88232235355;5004.449510553836;5874.383189765077;5879.79420720277;5881.823545293627;5882.852928676817;4072.9592704073175;5128.666636668605;5148.897022059576;5200.505951934598;5204.42751297357;13206.537771177962;13480.988925830601;13533.458661653522;13631.931840340834;13647.795283070762;2037.3989673046487;2140.9785902140934;2282.5777231212114;2370.727366352857;2447.0334217983973;9737.610495580238;9860.112589866947;10087.041731035613;10105.545250463747;10207.591696332138;1630.1278815833473;1650.636859890822;1691.5432833134985;1746.6506698660319;1748.6852366574012;92550;93111.99999999932;97037.97280225891;99970.04762427702;102357;10773.515191816387;10958.616448424425;11111.772467089286;11151.275167114112;11261.94369028166;13631.727365452834;13750.174989500661;13762.724745505035;13771.380287887068;13806.65483362924;8379.581020948928;8561.272291855137;8568.828623427496;8665.220130188187;8668.133186681407;7200.423966082805;8674.566271686517;8691;8698.086700896441;8791.296696264284;4299.247631664509;4428.202923473741;4431.091626216617;4454.77726113693;4470.888227794266;1728.5937804615905;1817.2458429751505;1865.7854346750298;1880.539267879351;1891.2718603337814;778.9532628042335;900.5362238447204;949.6826948362985;967.5936106835146;1021.1014307409432;19260.422187334403;19579.902100489406;19861.60276794456;19925.402237932594;20058.598828023358;934.5187228577288;965.6813251626946;978.256525040974;990.7671697151163;1000.9849852252222;10687;10843.132549396028;10953;10956.178286628534;10973.561057557768;541367;570871;597217;633430;636031;91777.54111229401;113779.8799054028;114379.42810285895;123054;123281.76718232704;1034.4155552113182;1037.1184493180888;1037.5019990404496;1077.1221454514546;1077.7898309829666;14601.415943362572;14882.702345952805;18012.54968625795;18131;18202;49212.76968075827;49855;49867.25199122089;49936;50021.249681254085;69623;69738.65130674314;76567;77163;77254.06864828424;7631.427642926692;7643.923560764321;7709.691612335444;7896.486728362638;8049.39629527776;11830.231034982698;12295.139340246096;12495.500179992698;12605.621831345074;12691.682707932376;10603.575856965943;10785.029347358775;10857.457127143452;11057.170712196457;11098.667039988813;10577.524011419659;10691.5188816505;10749.355038697704;10957.616483423042;11124;5825.0388685866355;8311.037457072176;10910.290831095954;11137.665870023913;11459.879922214417;7467;7584.886226706714;7692.923070769444;7944.6424910877995;8150.103488616129;8098.5545794981845;9830.459324736921;10405.427701476188;10884.074853637212;10915.23593348458 05:02:20 INFO - PROCESS | 1665 | |3;mootools.html;1559.5477311579643;1596.3295415925306;1736.3054778088772;1778.839904408603;1910.8757930734494;8739;8748.387612867102;8919.974202966652;8937.910620893788;8942.016378198394;7748.5350878947165;7988.480748751327;8476.110008449117;8693.69572064978;8696.608652610632;2453.43570978675;2490.5517006938744;2492.028109037468;2492.663490428787;2497.300755788382;2190.3319487556287;2429.331933718224;2504.3113143885394;2516.7231604523486;2520.3195137312914;2214.712087428632;2426.696662917135;2493.6010238361864;2500.3999040230306;2501.162110692911;2438.2197696737053;2450.5956517174645;2469.8271121021535;2470.2712699753615;2477.343503971449;2434.707835059804;2454.779069883719;2460.5817011108156;2464.866161565682;2469.8147638927153;8625.65497380104;8679.652813887436;8716.25911797498;8725.563721813947;8824.249938755214;5473.370562385337;5626.718664066781;5633.521150702195;5641.887162256773;5645.15322701594;3578.177019285579;3663.010987033486;3673.448982652597;3684.852605895761;3692.8153592320277;950.2825366848055;1025.077430312717;1042.3954106618144;1052.2055847834836;1068.2949253492657;12262.44818983139;12274.570390036306;12312.322822244772;12316.692082697915;12324.383780810924;4070.104576993057;4815.807367705253;4824.34871292371;4841.394825646794;4920.950790492049;10338.948305258426;10340.638077667245;10358.741031474123;10358.912314207051;10366.948165259126;5524.66851988882;5537;5546.057170281062;5550.417206193372;5554.111342185231;4980.950190498049;5034.219695947061;5046.016026874798;5048.29323894655;5079.263506791568;3941.940870886939;3975.582563830814;4000.179963107556;4027.7381970171846;4031.677465802729;1215.3862299538675;1264.974700506003;1298.4676282724038;1317.2557505009638;1326.2374134872487;1140.0138879868853;1140.7775483780583;1162.6686394377562;1322.2264974989546;1395.3511617098097;6781.287964763727;8103.797405064921;8157.79605509867;8194.631241594143;8202.38482113844;1180.710725872167;1187.7268228307537;1187.768385164902;1200.327816422806;1211.4124649544963;5364.624476286701;6222.875542489125;6310.116583678288;6317.210348706411;6357.1735674362035;10288.074073333435;10593.84109238362;10654.254202205771;10729.409882456384;10752.784944301071;10423.791524169474;10463;10578.259521833552;10628.627998020187;10638.893611063944;8234.917650823534;8392.958035209786;8424.536650484282;8457.323414126855;8471.830563388698;8039.954805875199;8162.10216876155;8205.876911846208;8243.422960392714;8387.035490918623;9429.81140377175;9606.327557071218;9741.074597913186;9784.85322720174;9834.311598188344;9696.660616878375;9867.259955503223;10022.047905448972;10032.147267482345;10043.246756493147;6438.6136831790245;7503.399728021855;7564.735234266775;7636.618169091412;7639.770806875803;8314.833703326021;8344.37417193725;8358;8379.664813407639;8396.496210227162;6115.388461153849;6192.752289908353;7343.559386436831;7448.14346350155;7554.924450755422;7175.103112110986;7288.854222915406;7317.817054573466;7384.889226661497;7429.925700742923;7659.234076592296;7722.150563437905;7751.806204844924;7753.612319384121;7753.961230193813;6874.450043996369;7019.052427922165;7021.508494405342;7043.436525077879;7074.575525468488;7106.964465177641;7172.282771722786;7188.892166617609;7198.6400679964745;7206.099237595301 05:02:20 INFO - PROCESS | 1665 | |4;prototype.html;296.5744157134512;322.9402560526302;324.4338629092234;331.41009003972937;333.97829141105825;6396.360363963601;6451.06459563363;6480.5139614528925;6562.409383155515;6690.431313338372;5756.338021127572;5839.686070634116;6409.711562979665;6414.518911081665;6421.325760795107;4499.955000450002;4625.190591646448;4685.812567497296;4713.222318317491;4769.165396055694;4686.648501362394;4711.552402521755;4723.811047558093;4724.31497432872;4751.311059896313;4679.508651591568;4685.7188568685815;4695.90608187836;4698.812047518095;4742.217534106885;3766.679832214265;4483.50681425043;4604.953950460519;4611.331356953223;4722.173619616553;3394.066631676284;3945.940810887839;4025.19496100781;4035.59644035597;4051.898702532431;6118.755249790003;6129.509639228895;6133.29467111279;6174.783882564134;6192.783252586183;5484.862878428032;5491.890162196733;5499.670019798785;5502.779888804443;5528.612997090206;5496.560275177976;6722.260551339349;6734.730610775563;6738.090357801683;6746.62893540855;3708.740388172829;4240.978795106035;4411.360352748833;4507.752073635948;4528.637708983307;5706.3722990471015;7073.151221853359;7110.395616372614;7116.893246601305;7203.099612548379;3734.2718169956597;4525.818967241274;4533.659975501851;4534.79593418297;4543.45478542577;3804.5434547854434;4567.817287308537;4583.931241031387;4592.632589392841;4613.584777369986;4738.715677059387;5190.2214667800135;5407.78368865241;5426.538744206708;5439.4288599696465;6567.835804104936;6852.828679283058;6854.149379667462;6856.5200435969;6876.140482439695;1569.9921500392425;1579.5577238373273;1582.327510807902;1592.824789273179;1597.3610555777782;989.569537251298;1006.3861044762689;1006.4112494190834;1009.5103874620803;1013.2350075692952;6061.424164704346;6326.5255105867245;6342.460890824332;6347.492200624031;6347.52393570484;6944.930550694428;7016.789496315118;7099.858002839915;7100.7869763907165;7101.857962840714;5564.221009058735;6130.785422510191;6348.238211414659;6406.775762848278;6418.679066046679;3723.7207209458857;3776.9244615107546;3779.981100094482;3783.3000894833995;3787.3182827090836;6022.518198544106;7110.57336559808;7417.8145546361775;7436.66535005926;7444.404447644176;7268.164161121539;7345.19202887682;7348.559086454829;7354.852902941911;7377.88933166003;7335.083114610674;7376.520526165782;7380.999999999893;7404.814879628052;7408.888866667005;7119.2168861426235;7272.127344718668;7296.96351518239;7312.890306645299;7317.817054573678;6732.798016059526;6999.650017499206;7008.649567521706;7011.3339232772805;7030.683619237045;7372.741954031583;7373.410127189704;7388.371988381048;7423.888641670272;7430.294122058396;3985.4619626350072;4339.110482351048;4494.842680506166;4531.048479819275;4541.364209010807;7093.900445430966;7097.361237488651;7104.147502299757;7104.964475177591;7124.5725256484775;6776.457883369417;6778.525503214728;6808.795736127924;6812.489063320171;6812.557183783038;6611.206655201407;6834.658267086526;6913.723451061901;6920.515563910478;6923.480738944499;6711.597304161766;6749.3925546699065;6751.257361690308;6768.323167683389;6777.1189745332795;6890.2420733720255;6920.169579650475;6926;6929.237783843672;6932.757353492603;5763.769449221984;6791.558548694319;6792.966035169792;6805;6811.795646130426 05:02:20 INFO - PROCESS | 1665 | |5;yui.html;672.4452326830366;731.0970950875668;757.6817736550648;769.2845653542204;789.8815177723341;14724.6318842029;15049.398024079037;15069.773953390708;15086.170260635661;15088.321025553849;12556.434960426803;13928.860711392908;14001.859981400208;14021;14039.157650540974;720.3049057659371;886.4238245140646;905.4160066756938;905.8686490458913;907.7549061753356;910.622091831889;915.2860768600469;917.775145089454;918.811643613061;920.5765347939922;947.1664934857316;960.6781728121088;962.7496850818768;965.7633879699483;966.1353088985354;753.9170691223961;946.7159852044359;960.2270172511126;961.7162936933621;962.523550842334;714.8963400306978;716.2622498826217;716.4662326566742;717.7236763845905;719.5574721546263;14779;14819.18494482803;14887.404503819835;14887.553373398816;14889.553313400615;12385.93807030959;12423.503059877685;12499.812502812465;12518.186317889307;12691.365431728376;9715.174210192143;9951.104400603908;10146.289759716823;10178.643747468803;10294.227932905062;3489.162600975773;3504.2641045380246;3516.1209697575605;3521.806300653463;3616.2044350242904;75661.62169189173;76485;77064;77741.83387249196;78565.21434785637;9614.278929080347;9787.46168960714;9807.509624518745;9909.950450247703;10030.799384012278;22072.558548828933;22087.33737987863;22101.447463813212;22115.778842211694;22145.892705364666;6779.796606101825;6919.688614012382;6951.443884489228;7012.1935977363255;7027.789166325018;4110.362893751473;4120.855770048034;4135.152293779768;4189.329707246826;4258.977845317102;3536.681698647134;3589.6589823966683;3590.0665826885197;3592.5688917329567;3596.2088340565033;643.1092936283188;643.2891654721559;646.7089809585706;657.3590749019763;658.4436151452015;577.3447137498885;579.6145563200508;580.5819809736983;604.1210039392761;615.9507239420835;8007.799805004922;8151.83696326082;8201.83596328082;8205.302549283258;8366.748997530083;529.8436961096486;542.5578153804637;564.3312674480753;583.547750493371;597.2623809595146;3795.582485926546;3860.1121741999495;3868.3086701757534;3889.922201555953;3947.644711975936;87112;87779.56110219409;90789;90969.54515227381;90980;72579.63710181521;73355;73401.63299183577;73833;76420.23579764132;62023.6898815503;62651.68674156691;63093;63231.36768632255;66057;7488.9141074542795;7528.585927774025;8164.959175204323;8311.750647480585;8313.916860831314;40445.191096177325;43649;47717;48151.27773083337;48282.959484437946;58068;58622.41377586169;59668.70165649144;59764;60305;10616.89383106159;10958.342499450058;11026.944865275622;11075.72310692239;11362.886371136183;9663.951680241555;11993.220440671328;12290.81563776562;12383.938080309541;12584.05619578517;11270.49282782293;11321.207515474167;11347.37589432589;11586.652400428;11668.358240296866;11238.213325067167;11358.829617555908;11477.942610286895;11578.294945617321;12389.93805030969;11035.172362072715;11330.206885517935;11454.942725286654;11490.88509114898;11521.423928803359;7561.470697051153;9020.14308640678;9087.636494540144;9337.953310233406;9404.670836520689;7689.082185903327;8243.670253189804;8459.519584072887;8889.4666320021;9326.813463730823 05:02:20 INFO - PROCESS | 1665 | __end_tp_report 05:02:20 INFO - PROCESS | 1665 | __start_cc_report 05:02:20 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1028 05:02:20 INFO - PROCESS | 1665 | __end_cc_report 05:02:20 INFO - PROCESS | 1665 | __startTimestamp1460030540257__endTimestamp 05:02:20 INFO - PROCESS | 1665 | 05:02:20 INFO - PROCESS | 1665 | ------- Summary: start ------- 05:02:20 INFO - PROCESS | 1665 | Number of tests: 6 05:02:20 INFO - PROCESS | 1665 | 05:02:20 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5710.37 Median:5869.30 stddev:655.62 (11.2%) stddev-sans-first:616.87 05:02:20 INFO - PROCESS | 1665 | Values: 2809.5 3445.6 3478.7 3511.7 3521.7 5586.2 5674.2 5680.7 5681.3 5687.5 5684.0 5688.3 5731.7 5845.5 5852.3 5092.4 5113.4 5116.7 5137.2 5242.2 5207.4 5212.8 5237.4 5274.3 5310.1 5090.7 5146.4 5194.6 5208.2 5234.4 5111.8 5165.1 5183.6 5200.5 5222.8 5142.0 5156.4 5162.2 5205.3 5211.0 5510.2 5598.7 5657.5 5676.5 5754.2 6246.4 6267.4 6269.2 6271.4 6296.3 6252.9 6313.2 6326.6 6326.6 6329.8 5679.0 5750.7 5787.9 5862.5 5869.3 6318.2 6339.0 6343.4 6347.4 6363.4 6061.8 6065.1 6108.9 6154.4 6172.3 6125.6 6192.3 6203.7 6210.5 6228.1 6101.2 6104.8 6216.3 6225.8 6242.9 6247.2 6255.1 6256.2 6264.1 6273.6 4821.3 4846.2 4873.5 4910.8 4929.8 4375.1 4376.2 4499.2 4500.8 4502.7 5701.0 5707.8 5707.8 5709.9 5713.6 6230.4 6278.3 6304.7 6314.2 6316.6 5553.7 5634.1 5669.5 5714.0 5746.7 5278.0 5332.3 5333.0 5337.0 5337.1 6294.0 6297.3 6311.1 6321.9 6332.4 6143.8 6209.0 6227.4 6322.2 6333.5 6299.3 6301.1 6303.3 6312.3 6315.3 6145.5 6168.7 6184.8 6192.6 6215.8 6122.8 6154.7 6233.4 6239.0 6276.6 6068.3 6078.1 6102.4 6104.9 6108.4 5123.4 5919.8 6112.5 6123.6 6125.8 4916.0 5765.8 5878.1 5969.5 6002.6 5048.1 5557.7 6070.9 6101.7 6112.5 5173.4 5375.4 5721.0 5955.5 5957.5 05:02:20 INFO - PROCESS | 1665 | 05:02:20 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:61442.07 Median:206892.72 stddev:126280.05 (61.0%) stddev-sans-first:126551.29 05:02:20 INFO - PROCESS | 1665 | Values: 458.0 576.8 580.5 631.3 633.9 30734.0 31158.0 31197.7 38955.4 39574.0 42231.9 42576.0 42673.6 42758.0 42818.4 9460.9 9678.1 9683.0 9685.0 9688.5 9466.6 9646.4 9649.0 9653.1 9653.8 9602.2 9683.4 9692.3 9779.0 9794.5 9679.6 9731.5 9741.9 9744.3 9755.2 7328.2 7419.0 7423.7 7423.9 7424.0 40989.4 41323.6 41395.8 41424.0 41691.6 3219.8 3375.3 3388.7 3486.0 3868.5 28260.2 29737.7 30264.1 30416.4 30485.1 2411.9 2686.6 2766.3 2804.3 2833.0 490553.0 491322.0 492225.0 492962.0 493304.0 64904.0 65138.7 65561.7 65821.0 66736.3 62992.4 65250.7 65412.3 65438.0 65461.0 14027.5 17186.3 17217.0 17251.7 17260.5 12650.4 12725.9 12731.5 12752.0 12759.2 18771.9 20502.5 20542.7 20572.4 20581.6 6724.9 7682.7 7693.3 7697.2 7819.6 821.7 945.4 1007.2 1035.6 1058.7 27606.0 27831.6 28528.6 28585.6 28629.4 1057.4 1148.8 1161.9 1190.8 1235.4 14222.1 14264.8 14533.0 14704.3 14980.9 535885.0 536327.0 542386.3 543589.0 544638.0 59304.1 59689.5 59710.7 59773.1 59841.0 1403.8 1435.3 1439.2 1455.6 1466.9 28443.0 28763.1 28802.0 29086.4 29564.0 43131.4 43546.6 43742.0 43765.3 43824.3 373835.0 376354.0 376383.0 376895.0 377053.0 28335.6 30456.2 32333.7 33712.2 33739.0 41929.2 42434.2 42727.4 42838.0 43458.1 34282.0 35419.8 35482.6 36356.8 36442.6 27837.6 34813.0 35290.6 35440.0 35635.6 35720.6 36398.8 36974.0 37413.8 37431.4 31455.4 31585.9 31864.5 32176.7 32223.7 22531.3 23640.6 23702.8 24119.0 24181.9 05:02:20 INFO - PROCESS | 1665 | 05:02:20 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:32932.76 Median:1404.47 stddev:98959.35 (7046.0%) stddev-sans-first:99206.74 05:02:20 INFO - PROCESS | 1665 | Values: 361.9 364.6 366.1 370.2 373.7 19296.5 20047.8 20405.7 20949.1 20972.8 11985.6 12344.3 12357.2 12364.2 12386.8 5068.5 5141.5 5282.3 6030.8 6040.3 5812.5 5910.5 5917.2 5947.1 5947.5 5191.2 5556.0 5669.2 5780.4 5883.9 5004.4 5874.4 5879.8 5881.8 5882.9 4073.0 5128.7 5148.9 5200.5 5204.4 13206.5 13481.0 13533.5 13631.9 13647.8 2037.4 2141.0 2282.6 2370.7 2447.0 9737.6 9860.1 10087.0 10105.5 10207.6 1630.1 1650.6 1691.5 1746.7 1748.7 92550.0 93112.0 97038.0 99970.0 102357.0 10773.5 10958.6 11111.8 11151.3 11261.9 13631.7 13750.2 13762.7 13771.4 13806.7 8379.6 8561.3 8568.8 8665.2 8668.1 7200.4 8674.6 8691.0 8698.1 8791.3 4299.2 4428.2 4431.1 4454.8 4470.9 1728.6 1817.2 1865.8 1880.5 1891.3 779.0 900.5 949.7 967.6 1021.1 19260.4 19579.9 19861.6 19925.4 20058.6 934.5 965.7 978.3 990.8 1001.0 10687.0 10843.1 10953.0 10956.2 10973.6 541367.0 570871.0 597217.0 633430.0 636031.0 91777.5 113779.9 114379.4 123054.0 123281.8 1034.4 1037.1 1037.5 1077.1 1077.8 14601.4 14882.7 18012.5 18131.0 18202.0 49212.8 49855.0 49867.3 49936.0 50021.2 69623.0 69738.7 76567.0 77163.0 77254.1 7631.4 7643.9 7709.7 7896.5 8049.4 11830.2 12295.1 12495.5 12605.6 12691.7 10603.6 10785.0 10857.5 11057.2 11098.7 10577.5 10691.5 10749.4 10957.6 11124.0 5825.0 8311.0 10910.3 11137.7 11459.9 7467.0 7584.9 7692.9 7944.6 8150.1 8098.6 9830.5 10405.4 10884.1 10915.2 05:02:20 INFO - PROCESS | 1665 | 05:02:20 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:6084.34 Median:5063.78 stddev:3205.26 (63.3%) stddev-sans-first:3196.20 05:02:20 INFO - PROCESS | 1665 | Values: 1559.5 1596.3 1736.3 1778.8 1910.9 8739.0 8748.4 8920.0 8937.9 8942.0 7748.5 7988.5 8476.1 8693.7 8696.6 2453.4 2490.6 2492.0 2492.7 2497.3 2190.3 2429.3 2504.3 2516.7 2520.3 2214.7 2426.7 2493.6 2500.4 2501.2 2438.2 2450.6 2469.8 2470.3 2477.3 2434.7 2454.8 2460.6 2464.9 2469.8 8625.7 8679.7 8716.3 8725.6 8824.2 5473.4 5626.7 5633.5 5641.9 5645.2 3578.2 3663.0 3673.4 3684.9 3692.8 950.3 1025.1 1042.4 1052.2 1068.3 12262.4 12274.6 12312.3 12316.7 12324.4 4070.1 4815.8 4824.3 4841.4 4921.0 10338.9 10340.6 10358.7 10358.9 10366.9 5524.7 5537.0 5546.1 5550.4 5554.1 4981.0 5034.2 5046.0 5048.3 5079.3 3941.9 3975.6 4000.2 4027.7 4031.7 1215.4 1265.0 1298.5 1317.3 1326.2 1140.0 1140.8 1162.7 1322.2 1395.4 6781.3 8103.8 8157.8 8194.6 8202.4 1180.7 1187.7 1187.8 1200.3 1211.4 5364.6 6222.9 6310.1 6317.2 6357.2 10288.1 10593.8 10654.3 10729.4 10752.8 10423.8 10463.0 10578.3 10628.6 10638.9 8234.9 8393.0 8424.5 8457.3 8471.8 8040.0 8162.1 8205.9 8243.4 8387.0 9429.8 9606.3 9741.1 9784.9 9834.3 9696.7 9867.3 10022.0 10032.1 10043.2 6438.6 7503.4 7564.7 7636.6 7639.8 8314.8 8344.4 8358.0 8379.7 8396.5 6115.4 6192.8 7343.6 7448.1 7554.9 7175.1 7288.9 7317.8 7384.9 7429.9 7659.2 7722.2 7751.8 7753.6 7754.0 6874.5 7019.1 7021.5 7043.4 7074.6 7107.0 7172.3 7188.9 7198.6 7206.1 05:02:20 INFO - PROCESS | 1665 | 05:02:20 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:5561.25 Median:6347.51 stddev:1807.62 (28.5%) stddev-sans-first:1768.97 05:02:20 INFO - PROCESS | 1665 | Values: 296.6 322.9 324.4 331.4 334.0 6396.4 6451.1 6480.5 6562.4 6690.4 5756.3 5839.7 6409.7 6414.5 6421.3 4500.0 4625.2 4685.8 4713.2 4769.2 4686.6 4711.6 4723.8 4724.3 4751.3 4679.5 4685.7 4695.9 4698.8 4742.2 3766.7 4483.5 4605.0 4611.3 4722.2 3394.1 3945.9 4025.2 4035.6 4051.9 6118.8 6129.5 6133.3 6174.8 6192.8 5484.9 5491.9 5499.7 5502.8 5528.6 5496.6 6722.3 6734.7 6738.1 6746.6 3708.7 4241.0 4411.4 4507.8 4528.6 5706.4 7073.2 7110.4 7116.9 7203.1 3734.3 4525.8 4533.7 4534.8 4543.5 3804.5 4567.8 4583.9 4592.6 4613.6 4738.7 5190.2 5407.8 5426.5 5439.4 6567.8 6852.8 6854.1 6856.5 6876.1 1570.0 1579.6 1582.3 1592.8 1597.4 989.6 1006.4 1006.4 1009.5 1013.2 6061.4 6326.5 6342.5 6347.5 6347.5 6944.9 7016.8 7099.9 7100.8 7101.9 5564.2 6130.8 6348.2 6406.8 6418.7 3723.7 3776.9 3780.0 3783.3 3787.3 6022.5 7110.6 7417.8 7436.7 7444.4 7268.2 7345.2 7348.6 7354.9 7377.9 7335.1 7376.5 7381.0 7404.8 7408.9 7119.2 7272.1 7297.0 7312.9 7317.8 6732.8 6999.7 7008.6 7011.3 7030.7 7372.7 7373.4 7388.4 7423.9 7430.3 3985.5 4339.1 4494.8 4531.0 4541.4 7093.9 7097.4 7104.1 7105.0 7124.6 6776.5 6778.5 6808.8 6812.5 6812.6 6611.2 6834.7 6913.7 6920.5 6923.5 6711.6 6749.4 6751.3 6768.3 6777.1 6890.2 6920.2 6926.0 6929.2 6932.8 5763.8 6791.6 6793.0 6805.0 6811.8 05:02:20 INFO - PROCESS | 1665 | 05:02:20 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17408.93 Median:30454.56 stddev:24033.25 (78.9%) stddev-sans-first:24067.81 05:02:20 INFO - PROCESS | 1665 | Values: 672.4 731.1 757.7 769.3 789.9 14724.6 15049.4 15069.8 15086.2 15088.3 12556.4 13928.9 14001.9 14021.0 14039.2 720.3 886.4 905.4 905.9 907.8 910.6 915.3 917.8 918.8 920.6 947.2 960.7 962.7 965.8 966.1 753.9 946.7 960.2 961.7 962.5 714.9 716.3 716.5 717.7 719.6 14779.0 14819.2 14887.4 14887.6 14889.6 12385.9 12423.5 12499.8 12518.2 12691.4 9715.2 9951.1 10146.3 10178.6 10294.2 3489.2 3504.3 3516.1 3521.8 3616.2 75661.6 76485.0 77064.0 77741.8 78565.2 9614.3 9787.5 9807.5 9910.0 10030.8 22072.6 22087.3 22101.4 22115.8 22145.9 6779.8 6919.7 6951.4 7012.2 7027.8 4110.4 4120.9 4135.2 4189.3 4259.0 3536.7 3589.7 3590.1 3592.6 3596.2 643.1 643.3 646.7 657.4 658.4 577.3 579.6 580.6 604.1 616.0 8007.8 8151.8 8201.8 8205.3 8366.7 529.8 542.6 564.3 583.5 597.3 3795.6 3860.1 3868.3 3889.9 3947.6 87112.0 87779.6 90789.0 90969.5 90980.0 72579.6 73355.0 73401.6 73833.0 76420.2 62023.7 62651.7 63093.0 63231.4 66057.0 7488.9 7528.6 8165.0 8311.8 8313.9 40445.2 43649.0 47717.0 48151.3 48283.0 58068.0 58622.4 59668.7 59764.0 60305.0 10616.9 10958.3 11026.9 11075.7 11362.9 9664.0 11993.2 12290.8 12383.9 12584.1 11270.5 11321.2 11347.4 11586.7 11668.4 11238.2 11358.8 11477.9 11578.3 12389.9 11035.2 11330.2 11454.9 11490.9 11521.4 7561.5 9020.1 9087.6 9338.0 9404.7 7689.1 8243.7 8459.5 8889.5 9326.8 05:02:20 INFO - PROCESS | 1665 | -------- Summary: end -------- 05:02:20 INFO - PROCESS | 1665 | 05:02:20 INFO - TEST-INFO | 1665: exit 0 05:02:20 INFO - TEST-OK | dromaeo_css | took 1112588ms 05:02:20 INFO - TEST-START | kraken 05:02:20 INFO - operating with platform_type : mac_ 05:02:20 INFO - Initialising browser for kraken test... 05:02:20 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpbhdy_z/profile http://localhost:49264/getInfo.html) 05:02:22 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 05:02:22 INFO - PROCESS | 1672 | colorDepth:24 05:02:22 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 05:02:22 INFO - PROCESS | 1672 | __metrics 05:02:23 INFO - PROCESS | 1672 | 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" 05:02:23 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:02:23 INFO - TEST-INFO | 1672: exit 0 05:02:23 INFO - Browser initialized. 05:02:23 INFO - Running cycle 1/1 for kraken test... 05:02:23 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpbhdy_z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:02:35 INFO - PROCESS | 1674 | RSS: Main: 236244992 05:02:35 INFO - PROCESS | 1674 | 05:02:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection) 05:02:38 INFO - PROCESS | 1674 | RSS: Main: 283025408 05:02:38 INFO - PROCESS | 1674 | 05:02:41 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-dft) 05:02:41 INFO - PROCESS | 1674 | RSS: Main: 293126144 05:02:41 INFO - PROCESS | 1674 | 05:02:45 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-fft) 05:02:45 INFO - PROCESS | 1674 | RSS: Main: 279379968 05:02:45 INFO - PROCESS | 1674 | 05:02:47 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator) 05:02:48 INFO - PROCESS | 1674 | RSS: Main: 281169920 05:02:48 INFO - PROCESS | 1674 | 05:02:50 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:02:50 INFO - PROCESS | 1674 | RSS: Main: 282370048 05:02:50 INFO - PROCESS | 1674 | 05:02:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom) 05:02:54 INFO - PROCESS | 1674 | RSS: Main: 282374144 05:02:54 INFO - PROCESS | 1674 | 05:02:57 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate) 05:02:57 INFO - PROCESS | 1674 | RSS: Main: 282476544 05:02:57 INFO - PROCESS | 1674 | 05:03:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial) 05:03:00 INFO - PROCESS | 1674 | RSS: Main: 282959872 05:03:00 INFO - PROCESS | 1674 | 05:03:02 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:03:03 INFO - PROCESS | 1674 | RSS: Main: 314130432 05:03:03 INFO - PROCESS | 1674 | 05:03:05 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:03:05 INFO - PROCESS | 1674 | RSS: Main: 313999360 05:03:05 INFO - PROCESS | 1674 | 05:03:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:03:08 INFO - PROCESS | 1674 | RSS: Main: 321089536 05:03:08 INFO - PROCESS | 1674 | 05:03:11 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:03:11 INFO - PROCESS | 1674 | RSS: Main: 321990656 05:03:11 INFO - PROCESS | 1674 | 05:03:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:03:14 INFO - PROCESS | 1674 | RSS: Main: 321929216 05:03:14 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49264/tests/kraken/driver.html?testName=ai-astar) 05:03:16 INFO - PROCESS | 1674 | RSS: Main: 384700416 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | __start_tp_report 05:03:16 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 05:03:16 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 05:03:16 INFO - PROCESS | 1674 | |i|pagename|runs| 05:03:16 INFO - PROCESS | 1674 | |0;ai-astar;110;109;107;109;109;110;117;110;111;111 05:03:16 INFO - PROCESS | 1674 | |1;audio-beat-detection;158;123;143;164;119;144;139;119;163;118 05:03:16 INFO - PROCESS | 1674 | |2;audio-dft;184;184;185;185;186;182;185;184;178;179 05:03:16 INFO - PROCESS | 1674 | |3;audio-fft;71;74;84;80;57;58;57;78;80;55 05:03:16 INFO - PROCESS | 1674 | |4;audio-oscillator;91;94;127;78;78;79;112;80;79;118 05:03:16 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;88;85;88;85;89;85;89;86;87;84 05:03:16 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;94;93;94;93;96;94;93;125;150 05:03:16 INFO - PROCESS | 1674 | |7;imaging-desaturate;84;87;86;85;86;86;84;86;84;87 05:03:16 INFO - PROCESS | 1674 | |8;json-parse-financial;52;52;62;74;79;79;80;53;74;72 05:03:16 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;65;73;76;72;71;67;67;69;66 05:03:16 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;85;83;83;83;83;83;82;82;84;84 05:03:16 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;133;144;115;127;121;125;123;119;137;129 05:03:16 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;140;130;143;139;144;141;135;139;136;140 05:03:16 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;67;66;68;66;66;68;75;93;96;95 05:03:16 INFO - PROCESS | 1674 | __end_tp_report 05:03:16 INFO - PROCESS | 1674 | __start_cc_report 05:03:16 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1316 05:03:16 INFO - PROCESS | 1674 | __end_cc_report 05:03:16 INFO - PROCESS | 1674 | __startTimestamp1460030596799__endTimestamp 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | ------- Summary: start ------- 05:03:16 INFO - PROCESS | 1674 | Number of tests: 14 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.00 stddev:2.63 (2.4%) stddev-sans-first:2.78 05:03:16 INFO - PROCESS | 1674 | Values: 110.0 109.0 107.0 109.0 109.0 110.0 117.0 110.0 111.0 111.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:143.50 stddev:18.56 (12.9%) stddev-sans-first:18.37 05:03:16 INFO - PROCESS | 1674 | Values: 158.0 123.0 143.0 164.0 119.0 144.0 139.0 119.0 163.0 118.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.50 stddev:2.70 (1.5%) stddev-sans-first:2.85 05:03:16 INFO - PROCESS | 1674 | Values: 184.0 184.0 185.0 185.0 186.0 182.0 185.0 184.0 178.0 179.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:69.40 Median:76.00 stddev:11.45 (15.1%) stddev-sans-first:12.13 05:03:16 INFO - PROCESS | 1674 | Values: 71.0 74.0 84.0 80.0 57.0 58.0 57.0 78.0 80.0 55.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:79.00 stddev:18.72 (23.7%) stddev-sans-first:19.83 05:03:16 INFO - PROCESS | 1674 | Values: 91.0 94.0 127.0 78.0 78.0 79.0 112.0 80.0 79.0 118.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:1.84 (2.1%) stddev-sans-first:1.88 05:03:16 INFO - PROCESS | 1674 | Values: 88.0 85.0 88.0 85.0 89.0 85.0 89.0 86.0 87.0 84.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:93.50 stddev:19.39 (20.7%) stddev-sans-first:20.26 05:03:16 INFO - PROCESS | 1674 | Values: 93.0 94.0 93.0 94.0 93.0 96.0 94.0 93.0 125.0 150.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:1.18 (1.4%) stddev-sans-first:1.12 05:03:16 INFO - PROCESS | 1674 | Values: 84.0 87.0 86.0 85.0 86.0 86.0 84.0 86.0 84.0 87.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:74.00 stddev:11.77 (15.9%) stddev-sans-first:11.02 05:03:16 INFO - PROCESS | 1674 | Values: 52.0 52.0 62.0 74.0 79.0 79.0 80.0 53.0 74.0 72.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:70.00 stddev:3.65 (5.2%) stddev-sans-first:3.68 05:03:16 INFO - PROCESS | 1674 | Values: 66.0 65.0 73.0 76.0 72.0 71.0 67.0 67.0 69.0 66.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.00 stddev:0.92 (1.1%) stddev-sans-first:0.71 05:03:16 INFO - PROCESS | 1674 | Values: 85.0 83.0 83.0 83.0 83.0 83.0 82.0 82.0 84.0 84.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:128.00 stddev:8.77 (6.9%) stddev-sans-first:9.06 05:03:16 INFO - PROCESS | 1674 | Values: 133.0 144.0 115.0 127.0 121.0 125.0 123.0 119.0 137.0 129.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.70 Median:140.00 stddev:4.11 (2.9%) stddev-sans-first:4.33 05:03:16 INFO - PROCESS | 1674 | Values: 140.0 130.0 143.0 139.0 144.0 141.0 135.0 139.0 136.0 140.0 05:03:16 INFO - PROCESS | 1674 | 05:03:16 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.00 Median:71.50 stddev:13.17 (18.4%) stddev-sans-first:13.56 05:03:16 INFO - PROCESS | 1674 | Values: 67.0 66.0 68.0 66.0 66.0 68.0 75.0 93.0 96.0 95.0 05:03:16 INFO - PROCESS | 1674 | -------- Summary: end -------- 05:03:16 INFO - PROCESS | 1674 | 05:03:17 INFO - TEST-INFO | 1674: exit 0 05:03:17 INFO - TEST-OK | kraken | took 56688ms 05:03:17 INFO - SUITE-END | took 1169s 05:03:17 INFO - Completed test suite (00:19:29) 05:03:17 INFO - javascript benchmark 05:03:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2809.452156829418, 3445.5865296164447, 3478.721702263819, 3511.6839484446396, 3521.6830485256323, 5586.217929489874, 5674.205611214428, 5680.715964201789, 5681.289838770153, 5687.544996400288, 5683.971580142103, 5688.288963879515, 5731.713414329277, 5845.532357411407, 5852.297724273093, 5092.439831618528, 5113.386393632751, 5116.718580478072, 5137.2037334213255, 5242.161254199329, 5207.401148867872, 5212.817551385702, 5237.423883372845, 5274.314339135907, 5310.123829568117, 5090.669106508084, 5146.433892271847, 5194.610404219679, 5208.218767184915, 5234.398044224925, 5111.821086262, 5165.1219292720325, 5183.559397451183, 5200.453952335026, 5222.817201397933, 5142.0, 5156.407013193493, 5162.199859021857, 5205.27126202332, 5211.0, 5510.201020851954, 5598.720063996825, 5657.519110875581, 5676.517496012844, 5754.1944127822135, 6246.406591373858, 6267.435930766253, 6269.153664255358, 6271.372862713738, 6296.338884417116, 6252.937470625327, 6313.242410910674, 6326.557141000132, 6326.620402775849, 6329.8417539561415, 5679.034564124109, 5750.683712395817, 5787.855303617401, 5862.530997520188, 5869.295684517885, 6318.241810982711, 6339.049142628643, 6343.397377249154, 6347.428731414195, 6363.363663633692, 6061.787837425757, 6065.090236464565, 6108.908366374508, 6154.3845615438095, 6172.321044685081, 6125.601835880653, 6192.256929168439, 6203.720832562545, 6210.50315974721, 6228.065790131516, 6101.186783078283, 6104.822969073627, 6216.2540495140875, 6225.750969961151, 6242.875142497124, 6247.187865577536, 6255.12428260044, 6256.217972753406, 6264.123022776815, 6273.62358258506, 4821.349117869113, 4846.176150054499, 4873.488283730228, 4910.754462276872, 4929.75351232437, 4375.124975005012, 4376.234159022159, 4499.167653984024, 4500.774961251925, 4502.707324023928, 5701.0, 5707.771689132472, 5707.800226992035, 5709.942900570941, 5713.628614140151, 6230.376962303824, 6278.277998030194, 6304.716287767062, 6314.242290925118, 6316.62100273985, 5553.694546799965, 5634.070378387521, 5669.489745922804, 5713.971430142823, 5746.712664366765, 5277.973610132001, 5332.253484512172, 5332.9733351333, 5336.973315133399, 5337.066013447631, 6293.968530157412, 6297.307296197506, 6311.147995020705, 6321.905171422341, 6332.366763323723, 6143.815685529441, 6208.9689551551955, 6227.377262273736, 6322.241331040304, 6333.524985626004, 6299.30707622152, 6301.0863424804065, 6303.306636269915, 6312.2740884796995, 6315.3053164152825, 6145.508359331154, 6168.722407491763, 6184.752609895554, 6192.628442293477, 6215.844603884939, 6122.785702500391, 6154.676744499954, 6233.376662333731, 6239.032949892774, 6276.623402595676, 6068.332483426731, 6078.088286757022, 6102.365138425952, 6104.938950610437, 6108.350745298833, 5123.436421993503, 5919.792807251725, 6112.533877023439, 6123.571350005457, 6125.84596676928, 4915.950840491692, 5765.827025189251, 5878.147668588116, 5969.492593129633, 6002.609830361012, 5048.066107770074, 5557.666540007613, 6070.939290607037, 6101.694915254131, 6112.541559382975, 5173.379194496684, 5375.354957405137, 5721.027425337619, 5955.5235581154275, 5957.523398128053], "unit": "score", "name": "dojo.html", "value": 5668.500551293257}, {"lowerIsBetter": false, "replicates": [457.96794224404306, 576.7513333632686, 580.5297708855829, 631.2929518938788, 633.8922383194858, 30734.0, 31158.0, 31197.68802311979, 38955.41566876499, 39574.01064973377, 42231.9442013949, 42576.0, 42673.57326426735, 42758.0, 42818.35772463408, 9460.8580871287, 9678.128968392843, 9683.0, 9685.0, 9688.46713430761, 9466.62133514662, 9646.37298575594, 9649.03509649033, 9653.082957119062, 9653.806923861519, 9602.183814375752, 9683.418994860296, 9692.321537492344, 9779.0, 9794.461304628243, 9679.5644196011, 9731.464769437678, 9741.853872191887, 9744.31789774716, 9755.170810481117, 7328.193898671142, 7418.962905185494, 7423.665935032936, 7423.925760742378, 7424.0, 40989.385159222635, 41323.58676413228, 41395.793021035, 41424.0, 41691.58308416908, 3219.7746157768966, 3375.3080618473155, 3388.6950174484414, 3486.0, 3868.458415821787, 28260.15219543417, 29737.70262297371, 30264.092077237718, 30416.391672166654, 30485.085447436613, 2411.915582954588, 2686.6104414859738, 2766.336079340944, 2804.326961529239, 2832.985835070832, 490553.0, 491322.0, 492225.0, 492962.0, 493304.0, 64904.0, 65138.67430662816, 65561.67219163873, 65821.0, 66736.33263667398, 62992.37007629865, 65250.673746630964, 65412.345876540625, 65438.0, 65461.0, 14027.509037183854, 17186.31254749796, 17217.0, 17251.74122388165, 17260.482185534453, 12650.367481625883, 12725.93637031809, 12731.490740370467, 12752.0, 12759.234445933274, 18771.86895970676, 20502.487437813874, 20542.69185962212, 20572.38282851517, 20581.58836823255, 6724.856774348373, 7682.654280557388, 7693.346065584376, 7697.23027697237, 7819.569923654253, 821.7411515372638, 945.3524335829978, 1007.2495990487134, 1035.6168217759478, 1058.692979036071, 27606.0, 27831.582526262122, 28528.572071418945, 28585.57121643177, 28629.427411451656, 1057.3972835483855, 1148.7989601819647, 1161.9070474362031, 1190.7737529869298, 1235.407004637761, 14222.080019197483, 14264.786028209584, 14533.0, 14704.26478676062, 14980.850191498164, 535885.0, 536327.0, 542386.2880685651, 543589.0, 544638.0, 59304.11043834346, 59689.50776230629, 59710.70144649249, 59773.103403448986, 59841.0, 1403.8175037245094, 1435.3038776193537, 1439.1509009684337, 1455.6433673749787, 1466.948656797007, 28443.0, 28763.06611566118, 28802.0, 29086.418271634026, 29564.0, 43131.353029703954, 43546.564534355515, 43742.0, 43765.34351984786, 43824.34263486114, 373835.0, 376354.0, 376383.0, 376895.0, 377053.0, 28335.57496637511, 30456.238594035327, 32333.706651733668, 33712.15719607029, 33739.0, 41929.161416772105, 42434.15131697411, 42727.359089613055, 42838.0, 43458.13083738371, 34281.97154085382, 35419.82290088533, 35482.645173547935, 36356.81821590981, 36442.635573644984, 27837.582436263878, 34812.9556113317, 35290.64709352976, 35440.0, 35635.643643563235, 35720.64279357173, 36398.8180059098, 36974.0, 37413.81293093517, 37431.43852842155, 31455.37089258248, 31585.89449369261, 31864.522032169072, 32176.678233217368, 32223.67776322207, 22531.324060278217, 23640.645390318816, 23702.762972370056, 24119.0, 24181.879090604434], "unit": "score", "name": "ext.html", "value": 19316.03389475298}, {"lowerIsBetter": false, "replicates": [361.9113317237276, 364.6481145694405, 366.073833201999, 370.2243799240592, 373.6711693709535, 19296.51758706033, 20047.799522004774, 20405.693914591295, 20949.05729242184, 20972.790272097274, 11985.64043078709, 12344.25934443934, 12357.19678220915, 12364.196327238724, 12386.81419778704, 5068.518490743373, 5141.485851414866, 5282.274337473646, 6030.819075427722, 6040.275166979968, 5812.534997200214, 5910.527157827385, 5917.20117784098, 5947.078202878539, 5947.4944629706315, 5191.247269145972, 5555.9721451531395, 5669.177969194464, 5780.421957804228, 5883.88232235355, 5004.449510553836, 5874.383189765077, 5879.79420720277, 5881.823545293627, 5882.852928676817, 4072.9592704073175, 5128.666636668605, 5148.897022059576, 5200.505951934598, 5204.42751297357, 13206.537771177962, 13480.988925830601, 13533.458661653522, 13631.931840340834, 13647.795283070762, 2037.3989673046487, 2140.9785902140934, 2282.5777231212114, 2370.727366352857, 2447.0334217983973, 9737.610495580238, 9860.112589866947, 10087.041731035613, 10105.545250463747, 10207.591696332138, 1630.1278815833473, 1650.636859890822, 1691.5432833134985, 1746.6506698660319, 1748.6852366574012, 92550.0, 93111.99999999932, 97037.97280225891, 99970.04762427702, 102357.0, 10773.515191816387, 10958.616448424425, 11111.772467089286, 11151.275167114112, 11261.94369028166, 13631.727365452834, 13750.174989500661, 13762.724745505035, 13771.380287887068, 13806.65483362924, 8379.581020948928, 8561.272291855137, 8568.828623427496, 8665.220130188187, 8668.133186681407, 7200.423966082805, 8674.566271686517, 8691.0, 8698.086700896441, 8791.296696264284, 4299.247631664509, 4428.202923473741, 4431.091626216617, 4454.77726113693, 4470.888227794266, 1728.5937804615905, 1817.2458429751505, 1865.7854346750298, 1880.539267879351, 1891.2718603337814, 778.9532628042335, 900.5362238447204, 949.6826948362985, 967.5936106835146, 1021.1014307409432, 19260.422187334403, 19579.902100489406, 19861.60276794456, 19925.402237932594, 20058.598828023358, 934.5187228577288, 965.6813251626946, 978.256525040974, 990.7671697151163, 1000.9849852252222, 10687.0, 10843.132549396028, 10953.0, 10956.178286628534, 10973.561057557768, 541367.0, 570871.0, 597217.0, 633430.0, 636031.0, 91777.54111229401, 113779.8799054028, 114379.42810285895, 123054.0, 123281.76718232704, 1034.4155552113182, 1037.1184493180888, 1037.5019990404496, 1077.1221454514546, 1077.7898309829666, 14601.415943362572, 14882.702345952805, 18012.54968625795, 18131.0, 18202.0, 49212.76968075827, 49855.0, 49867.25199122089, 49936.0, 50021.249681254085, 69623.0, 69738.65130674314, 76567.0, 77163.0, 77254.06864828424, 7631.427642926692, 7643.923560764321, 7709.691612335444, 7896.486728362638, 8049.39629527776, 11830.231034982698, 12295.139340246096, 12495.500179992698, 12605.621831345074, 12691.682707932376, 10603.575856965943, 10785.029347358775, 10857.457127143452, 11057.170712196457, 11098.667039988813, 10577.524011419659, 10691.5188816505, 10749.355038697704, 10957.616483423042, 11124.0, 5825.0388685866355, 8311.037457072176, 10910.290831095954, 11137.665870023913, 11459.879922214417, 7467.0, 7584.886226706714, 7692.923070769444, 7944.6424910877995, 8150.103488616129, 8098.5545794981845, 9830.459324736921, 10405.427701476188, 10884.074853637212, 10915.23593348458], "unit": "score", "name": "jquery.html", "value": 8901.31102945585}, {"lowerIsBetter": false, "replicates": [1559.5477311579643, 1596.3295415925306, 1736.3054778088772, 1778.839904408603, 1910.8757930734494, 8739.0, 8748.387612867102, 8919.974202966652, 8937.910620893788, 8942.016378198394, 7748.5350878947165, 7988.480748751327, 8476.110008449117, 8693.69572064978, 8696.608652610632, 2453.43570978675, 2490.5517006938744, 2492.028109037468, 2492.663490428787, 2497.300755788382, 2190.3319487556287, 2429.331933718224, 2504.3113143885394, 2516.7231604523486, 2520.3195137312914, 2214.712087428632, 2426.696662917135, 2493.6010238361864, 2500.3999040230306, 2501.162110692911, 2438.2197696737053, 2450.5956517174645, 2469.8271121021535, 2470.2712699753615, 2477.343503971449, 2434.707835059804, 2454.779069883719, 2460.5817011108156, 2464.866161565682, 2469.8147638927153, 8625.65497380104, 8679.652813887436, 8716.25911797498, 8725.563721813947, 8824.249938755214, 5473.370562385337, 5626.718664066781, 5633.521150702195, 5641.887162256773, 5645.15322701594, 3578.177019285579, 3663.010987033486, 3673.448982652597, 3684.852605895761, 3692.8153592320277, 950.2825366848055, 1025.077430312717, 1042.3954106618144, 1052.2055847834836, 1068.2949253492657, 12262.44818983139, 12274.570390036306, 12312.322822244772, 12316.692082697915, 12324.383780810924, 4070.104576993057, 4815.807367705253, 4824.34871292371, 4841.394825646794, 4920.950790492049, 10338.948305258426, 10340.638077667245, 10358.741031474123, 10358.912314207051, 10366.948165259126, 5524.66851988882, 5537.0, 5546.057170281062, 5550.417206193372, 5554.111342185231, 4980.950190498049, 5034.219695947061, 5046.016026874798, 5048.29323894655, 5079.263506791568, 3941.940870886939, 3975.582563830814, 4000.179963107556, 4027.7381970171846, 4031.677465802729, 1215.3862299538675, 1264.974700506003, 1298.4676282724038, 1317.2557505009638, 1326.2374134872487, 1140.0138879868853, 1140.7775483780583, 1162.6686394377562, 1322.2264974989546, 1395.3511617098097, 6781.287964763727, 8103.797405064921, 8157.79605509867, 8194.631241594143, 8202.38482113844, 1180.710725872167, 1187.7268228307537, 1187.768385164902, 1200.327816422806, 1211.4124649544963, 5364.624476286701, 6222.875542489125, 6310.116583678288, 6317.210348706411, 6357.1735674362035, 10288.074073333435, 10593.84109238362, 10654.254202205771, 10729.409882456384, 10752.784944301071, 10423.791524169474, 10463.0, 10578.259521833552, 10628.627998020187, 10638.893611063944, 8234.917650823534, 8392.958035209786, 8424.536650484282, 8457.323414126855, 8471.830563388698, 8039.954805875199, 8162.10216876155, 8205.876911846208, 8243.422960392714, 8387.035490918623, 9429.81140377175, 9606.327557071218, 9741.074597913186, 9784.85322720174, 9834.311598188344, 9696.660616878375, 9867.259955503223, 10022.047905448972, 10032.147267482345, 10043.246756493147, 6438.6136831790245, 7503.399728021855, 7564.735234266775, 7636.618169091412, 7639.770806875803, 8314.833703326021, 8344.37417193725, 8358.0, 8379.664813407639, 8396.496210227162, 6115.388461153849, 6192.752289908353, 7343.559386436831, 7448.14346350155, 7554.924450755422, 7175.103112110986, 7288.854222915406, 7317.817054573466, 7384.889226661497, 7429.925700742923, 7659.234076592296, 7722.150563437905, 7751.806204844924, 7753.612319384121, 7753.961230193813, 6874.450043996369, 7019.052427922165, 7021.508494405342, 7043.436525077879, 7074.575525468488, 7106.964465177641, 7172.282771722786, 7188.892166617609, 7198.6400679964745, 7206.099237595301], "unit": "score", "name": "mootools.html", "value": 4964.6109651907955}, {"lowerIsBetter": false, "replicates": [296.5744157134512, 322.9402560526302, 324.4338629092234, 331.41009003972937, 333.97829141105825, 6396.360363963601, 6451.06459563363, 6480.5139614528925, 6562.409383155515, 6690.431313338372, 5756.338021127572, 5839.686070634116, 6409.711562979665, 6414.518911081665, 6421.325760795107, 4499.955000450002, 4625.190591646448, 4685.812567497296, 4713.222318317491, 4769.165396055694, 4686.648501362394, 4711.552402521755, 4723.811047558093, 4724.31497432872, 4751.311059896313, 4679.508651591568, 4685.7188568685815, 4695.90608187836, 4698.812047518095, 4742.217534106885, 3766.679832214265, 4483.50681425043, 4604.953950460519, 4611.331356953223, 4722.173619616553, 3394.066631676284, 3945.940810887839, 4025.19496100781, 4035.59644035597, 4051.898702532431, 6118.755249790003, 6129.509639228895, 6133.29467111279, 6174.783882564134, 6192.783252586183, 5484.862878428032, 5491.890162196733, 5499.670019798785, 5502.779888804443, 5528.612997090206, 5496.560275177976, 6722.260551339349, 6734.730610775563, 6738.090357801683, 6746.62893540855, 3708.740388172829, 4240.978795106035, 4411.360352748833, 4507.752073635948, 4528.637708983307, 5706.3722990471015, 7073.151221853359, 7110.395616372614, 7116.893246601305, 7203.099612548379, 3734.2718169956597, 4525.818967241274, 4533.659975501851, 4534.79593418297, 4543.45478542577, 3804.5434547854434, 4567.817287308537, 4583.931241031387, 4592.632589392841, 4613.584777369986, 4738.715677059387, 5190.2214667800135, 5407.78368865241, 5426.538744206708, 5439.4288599696465, 6567.835804104936, 6852.828679283058, 6854.149379667462, 6856.5200435969, 6876.140482439695, 1569.9921500392425, 1579.5577238373273, 1582.327510807902, 1592.824789273179, 1597.3610555777782, 989.569537251298, 1006.3861044762689, 1006.4112494190834, 1009.5103874620803, 1013.2350075692952, 6061.424164704346, 6326.5255105867245, 6342.460890824332, 6347.492200624031, 6347.52393570484, 6944.930550694428, 7016.789496315118, 7099.858002839915, 7100.7869763907165, 7101.857962840714, 5564.221009058735, 6130.785422510191, 6348.238211414659, 6406.775762848278, 6418.679066046679, 3723.7207209458857, 3776.9244615107546, 3779.981100094482, 3783.3000894833995, 3787.3182827090836, 6022.518198544106, 7110.57336559808, 7417.8145546361775, 7436.66535005926, 7444.404447644176, 7268.164161121539, 7345.19202887682, 7348.559086454829, 7354.852902941911, 7377.88933166003, 7335.083114610674, 7376.520526165782, 7380.999999999893, 7404.814879628052, 7408.888866667005, 7119.2168861426235, 7272.127344718668, 7296.96351518239, 7312.890306645299, 7317.817054573678, 6732.798016059526, 6999.650017499206, 7008.649567521706, 7011.3339232772805, 7030.683619237045, 7372.741954031583, 7373.410127189704, 7388.371988381048, 7423.888641670272, 7430.294122058396, 3985.4619626350072, 4339.110482351048, 4494.842680506166, 4531.048479819275, 4541.364209010807, 7093.900445430966, 7097.361237488651, 7104.147502299757, 7104.964475177591, 7124.5725256484775, 6776.457883369417, 6778.525503214728, 6808.795736127924, 6812.489063320171, 6812.557183783038, 6611.206655201407, 6834.658267086526, 6913.723451061901, 6920.515563910478, 6923.480738944499, 6711.597304161766, 6749.3925546699065, 6751.257361690308, 6768.323167683389, 6777.1189745332795, 6890.2420733720255, 6920.169579650475, 6926.0, 6929.237783843672, 6932.757353492603, 5763.769449221984, 6791.558548694319, 6792.966035169792, 6805.0, 6811.795646130426], "unit": "score", "name": "prototype.html", "value": 4958.837078190376}, {"lowerIsBetter": false, "replicates": [672.4452326830366, 731.0970950875668, 757.6817736550648, 769.2845653542204, 789.8815177723341, 14724.6318842029, 15049.398024079037, 15069.773953390708, 15086.170260635661, 15088.321025553849, 12556.434960426803, 13928.860711392908, 14001.859981400208, 14021.0, 14039.157650540974, 720.3049057659371, 886.4238245140646, 905.4160066756938, 905.8686490458913, 907.7549061753356, 910.622091831889, 915.2860768600469, 917.775145089454, 918.811643613061, 920.5765347939922, 947.1664934857316, 960.6781728121088, 962.7496850818768, 965.7633879699483, 966.1353088985354, 753.9170691223961, 946.7159852044359, 960.2270172511126, 961.7162936933621, 962.523550842334, 714.8963400306978, 716.2622498826217, 716.4662326566742, 717.7236763845905, 719.5574721546263, 14779.0, 14819.18494482803, 14887.404503819835, 14887.553373398816, 14889.553313400615, 12385.93807030959, 12423.503059877685, 12499.812502812465, 12518.186317889307, 12691.365431728376, 9715.174210192143, 9951.104400603908, 10146.289759716823, 10178.643747468803, 10294.227932905062, 3489.162600975773, 3504.2641045380246, 3516.1209697575605, 3521.806300653463, 3616.2044350242904, 75661.62169189173, 76485.0, 77064.0, 77741.83387249196, 78565.21434785637, 9614.278929080347, 9787.46168960714, 9807.509624518745, 9909.950450247703, 10030.799384012278, 22072.558548828933, 22087.33737987863, 22101.447463813212, 22115.778842211694, 22145.892705364666, 6779.796606101825, 6919.688614012382, 6951.443884489228, 7012.1935977363255, 7027.789166325018, 4110.362893751473, 4120.855770048034, 4135.152293779768, 4189.329707246826, 4258.977845317102, 3536.681698647134, 3589.6589823966683, 3590.0665826885197, 3592.5688917329567, 3596.2088340565033, 643.1092936283188, 643.2891654721559, 646.7089809585706, 657.3590749019763, 658.4436151452015, 577.3447137498885, 579.6145563200508, 580.5819809736983, 604.1210039392761, 615.9507239420835, 8007.799805004922, 8151.83696326082, 8201.83596328082, 8205.302549283258, 8366.748997530083, 529.8436961096486, 542.5578153804637, 564.3312674480753, 583.547750493371, 597.2623809595146, 3795.582485926546, 3860.1121741999495, 3868.3086701757534, 3889.922201555953, 3947.644711975936, 87112.0, 87779.56110219409, 90789.0, 90969.54515227381, 90980.0, 72579.63710181521, 73355.0, 73401.63299183577, 73833.0, 76420.23579764132, 62023.6898815503, 62651.68674156691, 63093.0, 63231.36768632255, 66057.0, 7488.9141074542795, 7528.585927774025, 8164.959175204323, 8311.750647480585, 8313.916860831314, 40445.191096177325, 43649.0, 47717.0, 48151.27773083337, 48282.959484437946, 58068.0, 58622.41377586169, 59668.70165649144, 59764.0, 60305.0, 10616.89383106159, 10958.342499450058, 11026.944865275622, 11075.72310692239, 11362.886371136183, 9663.951680241555, 11993.220440671328, 12290.81563776562, 12383.938080309541, 12584.05619578517, 11270.49282782293, 11321.207515474167, 11347.37589432589, 11586.652400428, 11668.358240296866, 11238.213325067167, 11358.829617555908, 11477.942610286895, 11578.294945617321, 12389.93805030969, 11035.172362072715, 11330.206885517935, 11454.942725286654, 11490.88509114898, 11521.423928803359, 7561.470697051153, 9020.14308640678, 9087.636494540144, 9337.953310233406, 9404.670836520689, 7689.082185903327, 8243.670253189804, 8459.519584072887, 8889.4666320021, 9326.813463730823], "unit": "score", "name": "yui.html", "value": 6773.914512708548}], "name": "dromaeo_css", "value": 7387.483733843717}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 109.0, 107.0, 109.0, 109.0, 110.0, 117.0, 110.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 110.3}, {"lowerIsBetter": true, "replicates": [158.0, 123.0, 143.0, 164.0, 119.0, 144.0, 139.0, 119.0, 163.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 139.0}, {"lowerIsBetter": true, "replicates": [184.0, 184.0, 185.0, 185.0, 186.0, 182.0, 185.0, 184.0, 178.0, 179.0], "unit": "score", "name": "audio-dft", "value": 183.2}, {"lowerIsBetter": true, "replicates": [71.0, 74.0, 84.0, 80.0, 57.0, 58.0, 57.0, 78.0, 80.0, 55.0], "unit": "score", "name": "audio-fft", "value": 69.4}, {"lowerIsBetter": true, "replicates": [91.0, 94.0, 127.0, 78.0, 78.0, 79.0, 112.0, 80.0, 79.0, 118.0], "unit": "score", "name": "audio-oscillator", "value": 93.6}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 88.0, 85.0, 89.0, 85.0, 89.0, 86.0, 87.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.6}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 93.0, 94.0, 93.0, 96.0, 94.0, 93.0, 125.0, 150.0], "unit": "score", "name": "imaging-darkroom", "value": 102.5}, {"lowerIsBetter": true, "replicates": [84.0, 87.0, 86.0, 85.0, 86.0, 86.0, 84.0, 86.0, 84.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 85.5}, {"lowerIsBetter": true, "replicates": [52.0, 52.0, 62.0, 74.0, 79.0, 79.0, 80.0, 53.0, 74.0, 72.0], "unit": "score", "name": "json-parse-financial", "value": 67.7}, {"lowerIsBetter": true, "replicates": [66.0, 65.0, 73.0, 76.0, 72.0, 71.0, 67.0, 67.0, 69.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.2}, {"lowerIsBetter": true, "replicates": [85.0, 83.0, 83.0, 83.0, 83.0, 83.0, 82.0, 82.0, 84.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.2}, {"lowerIsBetter": true, "replicates": [133.0, 144.0, 115.0, 127.0, 121.0, 125.0, 123.0, 119.0, 137.0, 129.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 127.3}, {"lowerIsBetter": true, "replicates": [140.0, 130.0, 143.0, 139.0, 144.0, 141.0, 135.0, 139.0, 136.0, 140.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 138.7}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 68.0, 66.0, 66.0, 68.0, 75.0, 93.0, 96.0, 95.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 76.0}], "name": "kraken", "value": 1432.2}]} 05:03:17 INFO - Return code: 0 05:03:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:03:17 ERROR - # TBPL SUCCESS # 05:03:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:03:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:03:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:03:17 INFO - 2016-04-07 05:03:17.590 screenresolution[1678:9648] starting screenresolution argv=screenresolution get 05:03:17 INFO - 2016-04-07 05:03:17.592 screenresolution[1678:9648] Display 0: 1600x1200x32@60 05:03:17 INFO - 2016-04-07 05:03:17.599 screenresolution[1679:9652] starting screenresolution argv=screenresolution list 05:03:17 INFO - Available Modes on Display 0 05:03:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:03:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:03:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:03:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:03:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:03:17 INFO - Intel Iris: 05:03:17 INFO - Chipset Model: Intel Iris 05:03:17 INFO - Type: GPU 05:03:17 INFO - Bus: Built-In 05:03:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:03:17 INFO - Vendor: Intel (0x8086) 05:03:17 INFO - Device ID: 0x0a2e 05:03:17 INFO - Revision ID: 0x0009 05:03:17 INFO - Displays: 05:03:17 INFO - Display: 05:03:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:03:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:03:17 INFO - Display Serial Number: 1600x1200 60 05:03:17 INFO - Main Display: Yes 05:03:17 INFO - Mirror: Off 05:03:17 INFO - Online: Yes 05:03:17 INFO - Rotation: Supported 05:03:17 INFO - Return code: 0 05:03:17 INFO - Running post-action listener: _resource_record_post_action 05:03:17 INFO - Running post-run listener: _resource_record_post_run 05:03:18 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 37742080; Write bytes: 692802560; Read time: 419; Write time: 1892 05:03:18 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 379802112; Write bytes: 374851072; Read time: 28221; Write time: 731 05:03:18 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 35948032; Write bytes: 297082368; Read time: 384; Write time: 1116 05:03:18 INFO - Running post-run listener: _upload_blobber_files 05:03:18 INFO - Blob upload gear active. 05:03:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:03: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 05:03: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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:03: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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:03:19 INFO - (blobuploader) - INFO - Open directory for files ... 05:03:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:03:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:03:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:20 INFO - (blobuploader) - INFO - Done attempting. 05:03:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:03:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:03:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:22 INFO - (blobuploader) - INFO - Done attempting. 05:03:22 INFO - (blobuploader) - INFO - Iteration through files over. 05:03:22 INFO - Return code: 0 05:03:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:03:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:03:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cc94c9a263fc48636fc57e460a72b0acdc204e1670ac4e9ff55dbf5aa0205314d270de4dbd83c4a61b0dec243b97ec9eb88c7f75c390327027feaa26fc6acb5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75cf198efd3e91a678b78a8eca797c5ba1e750243519cd0300dd618d8b4b6356f8fb8210d9731589def2b1282505e6bd5ef9a3fda3f34cbfee255b780b14d33a"} 05:03:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:03:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:03:22 INFO - Contents: 05:03:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cc94c9a263fc48636fc57e460a72b0acdc204e1670ac4e9ff55dbf5aa0205314d270de4dbd83c4a61b0dec243b97ec9eb88c7f75c390327027feaa26fc6acb5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75cf198efd3e91a678b78a8eca797c5ba1e750243519cd0300dd618d8b4b6356f8fb8210d9731589def2b1282505e6bd5ef9a3fda3f34cbfee255b780b14d33a"} 05:03:22 INFO - Running post-run listener: copy_logs_to_upload_dir 05:03:22 INFO - Copying logs to upload dir... 05:03:22 INFO - mkdir: /builds/slave/test/build/upload/logs 05:03:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.758680 ========= master_lag: 1.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-04-07 05:03:23.313932) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:03:23.317229) ========= 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.gA5on8CTNz/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.bJ688Wqvol/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cc94c9a263fc48636fc57e460a72b0acdc204e1670ac4e9ff55dbf5aa0205314d270de4dbd83c4a61b0dec243b97ec9eb88c7f75c390327027feaa26fc6acb5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75cf198efd3e91a678b78a8eca797c5ba1e750243519cd0300dd618d8b4b6356f8fb8210d9731589def2b1282505e6bd5ef9a3fda3f34cbfee255b780b14d33a"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011096 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1459936821/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cc94c9a263fc48636fc57e460a72b0acdc204e1670ac4e9ff55dbf5aa0205314d270de4dbd83c4a61b0dec243b97ec9eb88c7f75c390327027feaa26fc6acb5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75cf198efd3e91a678b78a8eca797c5ba1e750243519cd0300dd618d8b4b6356f8fb8210d9731589def2b1282505e6bd5ef9a3fda3f34cbfee255b780b14d33a"}' ========= master_lag: 0.19 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:03:23.513771) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:03:23.514110) ========= 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.gA5on8CTNz/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.bJ688Wqvol/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005196 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:03:23.638367) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:03:23.639060) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:03:23.639946) ========= ========= Total master_lag: 5.80 =========