builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0159 starttime: 1460030743.53 results: success (0) buildid: 20160407044324 builduid: a9305b0a860b49cd938969825d3ebac1 revision: 2355485d9a8e09c7cfe47063bd192b72ec34d286 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.526041) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.526519) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.526858) ========= 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.XtoGk8U16N/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.zQV3ES60hH/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005708 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.551665) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.552051) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.569771) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.570199) ========= 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.XtoGk8U16N/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.zQV3ES60hH/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005515 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.619910) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.620461) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.621156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:43.621680) ========= 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.XtoGk8U16N/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.zQV3ES60hH/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:05:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-04-07 05:05:44 (46.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.484219 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:44.121923) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:44.122350) ========= 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.XtoGk8U16N/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.zQV3ES60hH/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.030574 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:44.170781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-07 05:05:44.171161) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2355485d9a8e09c7cfe47063bd192b72ec34d286 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2355485d9a8e09c7cfe47063bd192b72ec34d286 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XtoGk8U16N/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.zQV3ES60hH/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:05:44,276 truncating revision to first 12 chars 2016-04-07 05:05:44,276 Setting DEBUG logging. 2016-04-07 05:05:44,276 attempt 1/10 2016-04-07 05:05:44,276 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2355485d9a8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-07 05:05:45,552 unpacking tar archive at: mozilla-aurora-2355485d9a8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.526711 ========= master_lag: 4.51 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-07 05:05:50.208053) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:50.208451) ========= echo 2355485d9a8e09c7cfe47063bd192b72ec34d286 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'2355485d9a8e09c7cfe47063bd192b72ec34d286'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XtoGk8U16N/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.zQV3ES60hH/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 2355485d9a8e09c7cfe47063bd192b72ec34d286 program finished with exit code 0 elapsedTime=0.006025 script_repo_revision: '2355485d9a8e09c7cfe47063bd192b72ec34d286' ========= master_lag: 0.89 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:51.106927) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:51.107277) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:51.130646) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-04-07 05:05:51.131072) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XtoGk8U16N/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.zQV3ES60hH/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:05:51 INFO - MultiFileLogger online at 20160407 05:05:51 in /builds/slave/test 05:05:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:05:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:51 INFO - {'append_to_log': False, 05:05:51 INFO - 'base_work_dir': '/builds/slave/test', 05:05:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:05:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:51 INFO - 'branch': 'Mozilla-Aurora', 05:05:51 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:51 INFO - 'config_files': ('talos/mac_config.py',), 05:05:51 INFO - 'default_actions': ('clobber', 05:05:51 INFO - 'read-buildbot-config', 05:05:51 INFO - 'download-and-extract', 05:05:51 INFO - 'populate-webroot', 05:05:51 INFO - 'create-virtualenv', 05:05:51 INFO - 'install', 05:05:51 INFO - 'run-tests'), 05:05:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:51 INFO - 'download_minidump_stackwalk': True, 05:05:51 INFO - 'download_symbols': 'ondemand', 05:05:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:51 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:51 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:51 INFO - 'installer_path': 'installer.exe', 05:05:51 INFO - 'log_level': 'info', 05:05:51 INFO - 'log_name': 'talos', 05:05:51 INFO - 'log_to_console': True, 05:05:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:05:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:05:51 INFO - 'opt_config_files': (), 05:05:51 INFO - 'pip_index': False, 05:05:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:51 INFO - 'cmd': ('bash', 05:05:51 INFO - '-c', 05:05:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:51 INFO - 'enabled': True, 05:05:51 INFO - 'halt_on_failure': False, 05:05:51 INFO - 'name': 'check_screen_resolution'},), 05:05:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:51 INFO - 'cmd': ('bash', 05:05:51 INFO - '-c', 05:05:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:51 INFO - 'enabled': True, 05:05:51 INFO - 'halt_on_failure': False, 05:05:51 INFO - 'name': 'check_screen_resolution'},), 05:05:51 INFO - 'run_cmd_checks_enabled': True, 05:05:51 INFO - 'sps_profile': False, 05:05:51 INFO - 'sps_profile_interval': 0, 05:05:51 INFO - 'suite': 'dromaeojs-e10s', 05:05:51 INFO - 'system_bits': '32', 05:05:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:51 INFO - 'title': 't-yosemite-r7-0159', 05:05:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:51 INFO - 'use_talos_json': True, 05:05:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:51 INFO - 'work_dir': 'build'} 05:05:51 INFO - ##### 05:05:51 INFO - ##### Running clobber step. 05:05:51 INFO - ##### 05:05:51 INFO - Running pre-action listener: _resource_record_pre_action 05:05:51 INFO - Running main action method: clobber 05:05:51 INFO - rmtree: /builds/slave/test/build 05:05:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:52 INFO - Running post-action listener: _resource_record_post_action 05:05:52 INFO - ##### 05:05:52 INFO - ##### Running read-buildbot-config step. 05:05:52 INFO - ##### 05:05:52 INFO - Running pre-action listener: _resource_record_pre_action 05:05:52 INFO - Running main action method: read_buildbot_config 05:05:52 INFO - Using buildbot properties: 05:05:52 INFO - { 05:05:52 INFO - "project": "", 05:05:52 INFO - "product": "firefox", 05:05:52 INFO - "script_repo_revision": "production", 05:05:52 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:05:52 INFO - "repository": "", 05:05:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:05:52 INFO - "buildid": "20160407044324", 05:05:52 INFO - "stage_platform": "macosx64", 05:05:52 INFO - "basedir": "/builds/slave/test", 05:05:52 INFO - "buildnumber": 248, 05:05:52 INFO - "platform": "yosemite_r7", 05:05:52 INFO - "mozci_request": { 05:05:52 INFO - "priority": -1, 05:05:52 INFO - "type": "trigger_all_talos_jobs", 05:05:52 INFO - "times": 6 05:05:52 INFO - }, 05:05:52 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:05:52 INFO - "slavebuilddir": "test", 05:05:52 INFO - "branch": "mozilla-aurora", 05:05:52 INFO - "revision": "2355485d9a8e09c7cfe47063bd192b72ec34d286", 05:05:52 INFO - "slavename": "t-yosemite-r7-0159", 05:05:52 INFO - "builduid": "a9305b0a860b49cd938969825d3ebac1", 05:05:52 INFO - "repo_path": "releases/mozilla-aurora" 05:05:52 INFO - } 05:05:52 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg. 05:05:52 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json. 05:05:52 INFO - Running post-action listener: _resource_record_post_action 05:05:52 INFO - ##### 05:05:52 INFO - ##### Running download-and-extract step. 05:05:52 INFO - ##### 05:05:52 INFO - Running pre-action listener: _resource_record_pre_action 05:05:52 INFO - Running main action method: download_and_extract 05:05:52 INFO - mkdir: /builds/slave/test/build/tests 05:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:52 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json 05:05:52 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 05:05:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 05:05:52 INFO - Downloaded 1277 bytes. 05:05:52 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 05:05:52 INFO - Using the following test package requirements: 05:05:52 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:52 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:52 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 05:05:52 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:52 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 05:05:52 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:52 INFO - u'jsshell-mac.zip'], 05:05:52 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:52 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 05:05:52 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:52 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:52 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 05:05:52 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:52 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 05:05:52 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:52 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 05:05:52 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:52 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:52 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 05:05:52 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:52 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:52 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:05:53 INFO - Downloaded 17904082 bytes. 05:05:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:05:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:05:56 INFO - Return code: 0 05:05:56 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:05:56 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')]} 05:05:56 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:56 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:05:56 INFO - Downloaded 17904082 bytes. 05:05:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:05:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:06:00 INFO - Return code: 0 05:06:00 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')]} 05:06:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip 05:06:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 05:06:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:06:00 INFO - Downloaded 10845168 bytes. 05:06:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:06:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:06:00 INFO - Return code: 0 05:06:00 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')]} 05:06:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:06:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 05:06:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 05:06:02 INFO - Downloaded 115185382 bytes. 05:06:02 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:06:02 INFO - mkdir: /builds/slave/test/properties 05:06:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:06:02 INFO - Writing to file /builds/slave/test/properties/build_url 05:06:02 INFO - Contents: 05:06:02 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:06:02 INFO - Running post-action listener: _resource_record_post_action 05:06:02 INFO - Running post-action listener: set_extra_try_arguments 05:06:02 INFO - ##### 05:06:02 INFO - ##### Running populate-webroot step. 05:06:02 INFO - ##### 05:06:02 INFO - Running pre-action listener: _resource_record_pre_action 05:06:02 INFO - Running main action method: populate_webroot 05:06:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:06:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:06:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:06:02 INFO - '--tppagecycles', 05:06:02 INFO - '7'], 05:06:02 INFO - 'tests': ['tsvgm']}}, 05:06:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:06:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:06:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:06:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:06:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'tests': ['damp', 'tps']}, 05:06:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['damp', 'tps']}, 05:06:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:06:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:06:02 INFO - 'other': {'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore', 05:06:02 INFO - 'tabpaint']}, 05:06:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore', 05:06:02 INFO - 'tabpaint']}, 05:06:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore', 05:06:02 INFO - 'tabpaint']}, 05:06:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore', 05:06:02 INFO - 'tabpaint']}, 05:06:02 INFO - 'other_l64': {'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore', 05:06:02 INFO - 'tabpaint']}, 05:06:02 INFO - 'other_nol64': {'tests': ['a11yr', 05:06:02 INFO - 'ts_paint', 05:06:02 INFO - 'tpaint', 05:06:02 INFO - 'sessionrestore', 05:06:02 INFO - 'sessionrestore_no_auto_restore', 05:06:02 INFO - 'tabpaint']}, 05:06:02 INFO - 'svgr': {'tests': ['tsvgx', 05:06:02 INFO - 'tsvgr_opacity', 05:06:02 INFO - 'tart', 05:06:02 INFO - 'tscrollx', 05:06:02 INFO - 'cart']}, 05:06:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['tsvgx', 05:06:02 INFO - 'tsvgr_opacity', 05:06:02 INFO - 'tart', 05:06:02 INFO - 'tscrollx', 05:06:02 INFO - 'cart']}, 05:06:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'tests': ['tp5o']}, 05:06:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--e10s'], 05:06:02 INFO - 'tests': ['tp5o']}, 05:06:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--xperf_path', 05:06:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:02 INFO - 'tests': ['tp5n']}, 05:06:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:06:02 INFO - 'talos_options': ['--e10s', 05:06:02 INFO - '--xperf_path', 05:06:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:02 INFO - 'tests': ['tp5n']}}, 05:06:02 INFO - 'talos.zip': {'path': '', 05:06:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:06:02 INFO - Running post-action listener: _resource_record_post_action 05:06:02 INFO - ##### 05:06:02 INFO - ##### Running create-virtualenv step. 05:06:02 INFO - ##### 05:06:02 INFO - Running pre-action listener: _resource_record_pre_action 05:06:02 INFO - Running main action method: create_virtualenv 05:06:02 INFO - Creating virtualenv /builds/slave/test/build/venv 05:06:02 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 05:06:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:06:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:06:02 INFO - Using real prefix '/tools/python27' 05:06:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:06:03 INFO - Installing distribute.............................................................................................................................................................................................done. 05:06:06 INFO - Installing pip.................done. 05:06:06 INFO - Return code: 0 05:06:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:06:06 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')]} 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:06 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')]} 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:06 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 0x1073eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea3ca49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, '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.XtoGk8U16N/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.zQV3ES60hH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:06:06 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 05:06:06 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 05:06:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:06:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:06 INFO - 'HOME': '/Users/cltbld', 05:06:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:06 INFO - 'LOGNAME': 'cltbld', 05:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:06 INFO - 'MOZ_NO_REMOTE': '1', 05:06:06 INFO - 'NO_EM_RESTART': '1', 05:06:06 INFO - 'PAGER': '/bin/cat', 05:06:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:06 INFO - 'PWD': '/builds/slave/test', 05:06:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:06 INFO - 'SHELL': '/bin/bash', 05:06:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:06:06 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:06:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:06 INFO - 'USER': 'cltbld', 05:06:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:06 INFO - 'XPC_FLAGS': '0x0', 05:06:06 INFO - 'XPC_SERVICE_NAME': '0', 05:06:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:06 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 05:06:06 INFO - Cleaning up... 05:06:06 INFO - Return code: 0 05:06:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:06:06 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')]} 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:06 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')]} 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:06 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 0x1073eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea3ca49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, '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.XtoGk8U16N/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.zQV3ES60hH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:06:06 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 05:06:06 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 05:06:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:06:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:06 INFO - 'HOME': '/Users/cltbld', 05:06:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:06 INFO - 'LOGNAME': 'cltbld', 05:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:06 INFO - 'MOZ_NO_REMOTE': '1', 05:06:06 INFO - 'NO_EM_RESTART': '1', 05:06:06 INFO - 'PAGER': '/bin/cat', 05:06:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:06 INFO - 'PWD': '/builds/slave/test', 05:06:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:06 INFO - 'SHELL': '/bin/bash', 05:06:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:06:06 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:06:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:06 INFO - 'USER': 'cltbld', 05:06:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:06 INFO - 'XPC_FLAGS': '0x0', 05:06:06 INFO - 'XPC_SERVICE_NAME': '0', 05:06:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:06 INFO - Downloading/unpacking psutil>=0.7.1 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:06:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:09 INFO - Installing collected packages: psutil 05:06:09 INFO - Running setup.py install for psutil 05:06:09 INFO - building 'psutil._psutil_osx' extension 05:06:09 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 05:06:10 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 05:06:10 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 05:06:10 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 05:06:10 INFO - building 'psutil._psutil_posix' extension 05:06:10 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 05:06:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:06:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:06:10 INFO - ^ 05:06:10 INFO - 1 warning generated. 05:06:10 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 05:06:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:10 INFO - Successfully installed psutil 05:06:10 INFO - Cleaning up... 05:06:10 INFO - Return code: 0 05:06:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:10 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 0x1073eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea3ca49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, '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.XtoGk8U16N/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.zQV3ES60hH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:06:10 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 05:06:10 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 05:06:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:06:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:10 INFO - 'HOME': '/Users/cltbld', 05:06:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:10 INFO - 'LOGNAME': 'cltbld', 05:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:10 INFO - 'MOZ_NO_REMOTE': '1', 05:06:10 INFO - 'NO_EM_RESTART': '1', 05:06:10 INFO - 'PAGER': '/bin/cat', 05:06:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:10 INFO - 'PWD': '/builds/slave/test', 05:06:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:10 INFO - 'SHELL': '/bin/bash', 05:06:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:06:10 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:06:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:10 INFO - 'USER': 'cltbld', 05:06:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:10 INFO - 'XPC_FLAGS': '0x0', 05:06:10 INFO - 'XPC_SERVICE_NAME': '0', 05:06:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:06: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 05:06: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 05:06: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 05:06: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 05:06: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 05:06: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 05:06:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:06:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:06:13 INFO - Installing collected packages: mozsystemmonitor 05:06:13 INFO - Running setup.py install for mozsystemmonitor 05:06:13 INFO - Successfully installed mozsystemmonitor 05:06:13 INFO - Cleaning up... 05:06:13 INFO - Return code: 0 05:06:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:06:13 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')]} 05:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:13 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')]} 05:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:13 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 0x1073eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea3ca49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, '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.XtoGk8U16N/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.zQV3ES60hH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:06:13 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 05:06:13 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 05:06:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:06:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:13 INFO - 'HOME': '/Users/cltbld', 05:06:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:13 INFO - 'LOGNAME': 'cltbld', 05:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:13 INFO - 'MOZ_NO_REMOTE': '1', 05:06:13 INFO - 'NO_EM_RESTART': '1', 05:06:13 INFO - 'PAGER': '/bin/cat', 05:06:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:13 INFO - 'PWD': '/builds/slave/test', 05:06:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:13 INFO - 'SHELL': '/bin/bash', 05:06:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:06:13 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:06:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:13 INFO - 'USER': 'cltbld', 05:06:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:13 INFO - 'XPC_FLAGS': '0x0', 05:06:13 INFO - 'XPC_SERVICE_NAME': '0', 05:06:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:13 INFO - Downloading/unpacking blobuploader==1.2.4 05:06:13 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 05:06:13 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 05:06:13 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 05:06:13 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 05:06:13 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 05:06:13 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 05:06:16 INFO - Downloading blobuploader-1.2.4.tar.gz 05:06:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:06:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:06:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:17 INFO - Downloading docopt-0.6.1.tar.gz 05:06:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:06:17 INFO - Installing collected packages: blobuploader, requests, docopt 05:06:17 INFO - Running setup.py install for blobuploader 05:06:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:06:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:06:17 INFO - Running setup.py install for requests 05:06:18 INFO - Running setup.py install for docopt 05:06:18 INFO - Successfully installed blobuploader requests docopt 05:06:18 INFO - Cleaning up... 05:06:18 INFO - Return code: 0 05:06:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:18 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 0x1073eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea3ca49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, '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.XtoGk8U16N/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.zQV3ES60hH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:06:18 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 05:06:18 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 05:06:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:06:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:18 INFO - 'HOME': '/Users/cltbld', 05:06:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:18 INFO - 'LOGNAME': 'cltbld', 05:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:18 INFO - 'MOZ_NO_REMOTE': '1', 05:06:18 INFO - 'NO_EM_RESTART': '1', 05:06:18 INFO - 'PAGER': '/bin/cat', 05:06:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:18 INFO - 'PWD': '/builds/slave/test', 05:06:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:18 INFO - 'SHELL': '/bin/bash', 05:06:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:06:18 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:06:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:18 INFO - 'USER': 'cltbld', 05:06:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:18 INFO - 'XPC_FLAGS': '0x0', 05:06:18 INFO - 'XPC_SERVICE_NAME': '0', 05:06:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:18 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-peenvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:18 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-wmzDFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:18 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-TbVDKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:18 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-5LSlcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-RtUkbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-oKr4G6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-VoBYnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-LNiyFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-40EED0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-7Zrj2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-eaN23L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gKrNbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lWz5sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-bHXPOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-fyLECt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-r5ymvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-AgMDAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:06:20 INFO - Running setup.py install for manifestparser 05:06:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:06:20 INFO - Running setup.py install for mozcrash 05:06:20 INFO - Running setup.py install for mozdebug 05:06:20 INFO - Running setup.py install for mozdevice 05:06:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:06:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:06:20 INFO - Running setup.py install for mozfile 05:06:21 INFO - Running setup.py install for mozhttpd 05:06:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:06:21 INFO - Running setup.py install for mozinfo 05:06:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:06:21 INFO - Running setup.py install for mozInstall 05:06:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:06:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:06:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:06:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:06:21 INFO - Running setup.py install for mozleak 05:06:21 INFO - Running setup.py install for mozlog 05:06:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:06:21 INFO - Running setup.py install for moznetwork 05:06:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:06:22 INFO - Running setup.py install for mozprocess 05:06:22 INFO - Running setup.py install for mozprofile 05:06:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:06:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:06:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:06:22 INFO - Running setup.py install for mozrunner 05:06:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:06:22 INFO - Running setup.py install for mozscreenshot 05:06:22 INFO - Running setup.py install for moztest 05:06:22 INFO - Running setup.py install for mozversion 05:06:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:06:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:06:22 INFO - Cleaning up... 05:06:22 INFO - Return code: 0 05:06:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:22 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 0x1073eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea3ca49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, '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.XtoGk8U16N/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.zQV3ES60hH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:06:22 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 05:06:22 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 05:06:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:06:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:22 INFO - 'HOME': '/Users/cltbld', 05:06:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:22 INFO - 'LOGNAME': 'cltbld', 05:06:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:22 INFO - 'MOZ_NO_REMOTE': '1', 05:06:22 INFO - 'NO_EM_RESTART': '1', 05:06:22 INFO - 'PAGER': '/bin/cat', 05:06:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:22 INFO - 'PWD': '/builds/slave/test', 05:06:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:22 INFO - 'SHELL': '/bin/bash', 05:06:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:06:22 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:06:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:22 INFO - 'USER': 'cltbld', 05:06:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:22 INFO - 'XPC_FLAGS': '0x0', 05:06:22 INFO - 'XPC_SERVICE_NAME': '0', 05:06:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-TRNzM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:23 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)) 05:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-i59dPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:23 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)) 05:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-yb9kXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:23 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)) 05:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-3fzVcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:23 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)) 05:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-n0y_J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:23 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)) 05:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-sKcv34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:23 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)) 05:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-EU_ODr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:23 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)) 05:06:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-n4Dp8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-j5JadM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-mAlC87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-oIqZ2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-U2id90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-8Dl2V6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-l8pYvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-aXjSgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-hpz361-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-T0SBfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:24 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)) 05:06:24 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)) 05:06:24 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)) 05:06:24 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)) 05:06:24 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)) 05:06:24 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)) 05:06:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:25 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 05:06:25 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 05:06:25 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 05:06:25 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 05:06:25 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 05:06:25 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 05:06:27 INFO - Downloading blessings-1.6.tar.gz 05:06:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:27 INFO - Installing collected packages: blessings 05:06:27 INFO - Running setup.py install for blessings 05:06:27 INFO - Successfully installed blessings 05:06:27 INFO - Cleaning up... 05:06:27 INFO - Return code: 0 05:06:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:27 INFO - Reading from file tmpfile_stdout 05:06:27 INFO - Current package versions: 05:06:27 INFO - blessings == 1.6 05:06:27 INFO - blobuploader == 1.2.4 05:06:27 INFO - docopt == 0.6.1 05:06:27 INFO - manifestparser == 1.1 05:06:27 INFO - mozInstall == 1.12 05:06:27 INFO - mozcrash == 0.17 05:06:27 INFO - mozdebug == 0.1 05:06:27 INFO - mozdevice == 0.48 05:06:27 INFO - mozfile == 1.2 05:06:27 INFO - mozhttpd == 0.7 05:06:27 INFO - mozinfo == 0.9 05:06:27 INFO - mozleak == 0.1 05:06:27 INFO - mozlog == 3.1 05:06:27 INFO - moznetwork == 0.27 05:06:27 INFO - mozprocess == 0.22 05:06:27 INFO - mozprofile == 0.28 05:06:27 INFO - mozrunner == 6.11 05:06:27 INFO - mozscreenshot == 0.1 05:06:27 INFO - mozsystemmonitor == 0.0 05:06:27 INFO - moztest == 0.7 05:06:27 INFO - mozversion == 1.4 05:06:27 INFO - psutil == 3.1.1 05:06:27 INFO - requests == 1.2.3 05:06:27 INFO - wsgiref == 0.1.2 05:06:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:28 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')]} 05:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:28 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 0x1073eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea3ca49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, '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.XtoGk8U16N/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.zQV3ES60hH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:06:28 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 05:06:28 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 05:06:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:06:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:28 INFO - 'HOME': '/Users/cltbld', 05:06:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:28 INFO - 'LOGNAME': 'cltbld', 05:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:28 INFO - 'MOZ_NO_REMOTE': '1', 05:06:28 INFO - 'NO_EM_RESTART': '1', 05:06:28 INFO - 'PAGER': '/bin/cat', 05:06:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:28 INFO - 'PWD': '/builds/slave/test', 05:06:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:28 INFO - 'SHELL': '/bin/bash', 05:06:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:06:28 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:06:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:28 INFO - 'USER': 'cltbld', 05:06:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:28 INFO - 'XPC_FLAGS': '0x0', 05:06:28 INFO - 'XPC_SERVICE_NAME': '0', 05:06:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:28 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)) 05:06:28 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)) 05:06:28 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)) 05:06:28 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)) 05:06:28 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)) 05:06:28 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)) 05:06:28 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)) 05:06:28 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)) 05:06:28 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)) 05:06:28 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)) 05:06:28 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)) 05:06:28 INFO - Cleaning up... 05:06:28 INFO - Return code: 0 05:06:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:28 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')]} 05:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:28 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')]} 05:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:28 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 0x1073eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea3ca49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, '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.XtoGk8U16N/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.zQV3ES60hH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:06:28 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 05:06:28 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 05:06:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:06:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:28 INFO - 'HOME': '/Users/cltbld', 05:06:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:28 INFO - 'LOGNAME': 'cltbld', 05:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:28 INFO - 'MOZ_NO_REMOTE': '1', 05:06:28 INFO - 'NO_EM_RESTART': '1', 05:06:28 INFO - 'PAGER': '/bin/cat', 05:06:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:28 INFO - 'PWD': '/builds/slave/test', 05:06:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:28 INFO - 'SHELL': '/bin/bash', 05:06:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:06:28 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:06:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:28 INFO - 'USER': 'cltbld', 05:06:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:28 INFO - 'XPC_FLAGS': '0x0', 05:06:28 INFO - 'XPC_SERVICE_NAME': '0', 05:06:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:28 INFO - Downloading/unpacking jsonschema 05:06:28 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 05:06:28 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 05:06:28 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 05:06:28 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 05:06:28 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 05:06:28 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 05:06:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:30 INFO - Downloading/unpacking functools32 (from jsonschema) 05:06:30 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 05:06:30 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 05:06:30 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 05:06:30 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 05:06:30 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 05:06:30 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 05:06:31 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:31 INFO - warning: no files found matching '*.txt' 05:06:31 INFO - no previously-included directories found matching 'build' 05:06:31 INFO - no previously-included directories found matching 'dist' 05:06:31 INFO - no previously-included directories found matching '.git*' 05:06:31 INFO - Installing collected packages: jsonschema, functools32 05:06:31 INFO - Running setup.py install for functools32 05:06:31 INFO - warning: no files found matching '*.txt' 05:06:31 INFO - no previously-included directories found matching 'build' 05:06:31 INFO - no previously-included directories found matching 'dist' 05:06:31 INFO - no previously-included directories found matching '.git*' 05:06:31 INFO - Successfully installed jsonschema functools32 05:06:31 INFO - Cleaning up... 05:06:31 INFO - Return code: 0 05:06:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:06:31 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')]} 05:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:31 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')]} 05:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:31 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 0x1073eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faea3ca49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, '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.XtoGk8U16N/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.zQV3ES60hH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 05:06:31 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 05:06:31 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 05:06:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:06:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:31 INFO - 'HOME': '/Users/cltbld', 05:06:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:31 INFO - 'LOGNAME': 'cltbld', 05:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:31 INFO - 'MOZ_NO_REMOTE': '1', 05:06:31 INFO - 'NO_EM_RESTART': '1', 05:06:31 INFO - 'PAGER': '/bin/cat', 05:06:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:31 INFO - 'PWD': '/builds/slave/test', 05:06:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:31 INFO - 'SHELL': '/bin/bash', 05:06:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:06:31 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:06:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:31 INFO - 'USER': 'cltbld', 05:06:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:31 INFO - 'XPC_FLAGS': '0x0', 05:06:31 INFO - 'XPC_SERVICE_NAME': '0', 05:06:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:31 INFO - Downloading/unpacking flake8 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 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 05:06:31 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 05:06:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:06:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:06:34 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 05:06:34 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 05:06:34 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 05:06:34 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 05:06:34 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 05:06:34 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 05:06:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:06:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:06:34 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 05:06:34 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 05:06:34 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 05:06:34 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 05:06:34 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 05:06:34 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 05:06:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:06:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:35 INFO - no previously-included directories found matching 'docs/_build' 05:06:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:06:35 INFO - Running setup.py install for pep8 05:06:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:35 INFO - no previously-included directories found matching 'docs/_build' 05:06:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:06:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:06:35 INFO - Cleaning up... 05:06:36 INFO - Return code: 0 05:06:36 INFO - Running post-action listener: _resource_record_post_action 05:06:36 INFO - Running post-action listener: _start_resource_monitoring 05:06:36 INFO - Starting resource monitoring. 05:06:36 INFO - ##### 05:06:36 INFO - ##### Running install step. 05:06:36 INFO - ##### 05:06:36 INFO - Running pre-action listener: _resource_record_pre_action 05:06:36 INFO - Running main action method: install 05:06:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:36 INFO - Reading from file tmpfile_stdout 05:06:36 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:36 INFO - Reading from file tmpfile_stdout 05:06:36 INFO - Output received: 05:06:36 INFO - Usage: mozinstall [options] installer 05:06:36 INFO - Options: 05:06:36 INFO - -h, --help show this help message and exit 05:06:36 INFO - -d DEST, --destination=DEST 05:06:36 INFO - Directory to install application into. [default: 05:06:36 INFO - "/builds/slave/test"] 05:06:36 INFO - --app=APP Application being installed. [default: firefox] 05:06:36 INFO - mkdir: /builds/slave/test/build/application 05:06:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:07:08 INFO - Reading from file tmpfile_stdout 05:07:08 INFO - Output received: 05:07:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:07:08 INFO - Running post-action listener: _resource_record_post_action 05:07:08 INFO - ##### 05:07:08 INFO - ##### Running run-tests step. 05:07:08 INFO - ##### 05:07:08 INFO - Running pre-action listener: _resource_record_pre_action 05:07:08 INFO - Running main action method: run_tests 05:07:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:08 INFO - 2016-04-07 05:07:08.552 screenresolution[1641:7382] starting screenresolution argv=screenresolution get 05:07:08 INFO - 2016-04-07 05:07:08.555 screenresolution[1641:7382] Display 0: 1600x1200x32@60 05:07:08 INFO - 2016-04-07 05:07:08.561 screenresolution[1642:7385] starting screenresolution argv=screenresolution list 05:07:08 INFO - Available Modes on Display 0 05:07:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:08 INFO - Intel Iris: 05:07:08 INFO - Chipset Model: Intel Iris 05:07:08 INFO - Type: GPU 05:07:08 INFO - Bus: Built-In 05:07:08 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:08 INFO - Vendor: Intel (0x8086) 05:07:08 INFO - Device ID: 0x0a2e 05:07:08 INFO - Revision ID: 0x0009 05:07:08 INFO - Displays: 05:07:08 INFO - Display: 05:07:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:08 INFO - Display Serial Number: 1600x1200 60 05:07:08 INFO - Main Display: Yes 05:07:08 INFO - Mirror: Off 05:07:08 INFO - Online: Yes 05:07:08 INFO - Rotation: Supported 05:07:08 INFO - Return code: 0 05:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:07:08 INFO - Python 2.7.3 05:07:08 INFO - Return code: 0 05:07:08 INFO - grabbing minidump binary from tooltool 05:07: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')]} 05:07:08 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 0x107290d30>, '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 0x1070f4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107287b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:07:08 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 05:07:08 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 05:07:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:07:08 INFO - Return code: 0 05:07:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:07:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:07:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:07:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:07:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:07:10 INFO - Return code: 0 05:07:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:07:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:07:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XtoGk8U16N/Render', 05:07:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:10 INFO - 'HOME': '/Users/cltbld', 05:07:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:10 INFO - 'LOGNAME': 'cltbld', 05:07:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:07:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:10 INFO - 'MOZ_NO_REMOTE': '1', 05:07:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:07:10 INFO - 'NO_EM_RESTART': '1', 05:07:10 INFO - 'PAGER': '/bin/cat', 05:07:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:10 INFO - 'PWD': '/builds/slave/test', 05:07:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:07:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:10 INFO - 'SHELL': '/bin/bash', 05:07:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zQV3ES60hH/Listeners', 05:07:10 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:07:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:10 INFO - 'USER': 'cltbld', 05:07:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:10 INFO - 'XPC_FLAGS': '0x0', 05:07:10 INFO - 'XPC_SERVICE_NAME': '0', 05:07:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:07:10 INFO - mozversion application_buildid: 20160406205952 05:07:10 INFO - mozversion application_changeset: 2355485d9a8e09c7cfe47063bd192b72ec34d286 05:07:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:07:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:07:10 INFO - mozversion application_name: Firefox 05:07:10 INFO - mozversion application_remotingname: firefox-dev 05:07:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:07:10 INFO - mozversion application_vendor: Mozilla 05:07:10 INFO - mozversion application_version: 47.0a2 05:07:10 INFO - mozversion platform_buildid: 20160406205952 05:07:10 INFO - mozversion platform_changeset: 2355485d9a8e09c7cfe47063bd192b72ec34d286 05:07:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:07:10 INFO - mozversion platform_version: 47.0a2 05:07:10 INFO - using testdate: 1460030830 05:07:10 INFO - actual date: 1460030830 05:07:10 INFO - starting webserver on 'localhost:49266' 05:07:10 INFO - SUITE-START | Running 2 tests 05:07:10 INFO - TEST-START | dromaeo_css 05:07:10 INFO - operating with platform_type : mac_ 05:07:10 INFO - Initialising browser for dromaeo_css test... 05:07:10 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpq68FgF/profile http://localhost:49266/getInfo.html) 05:07:13 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 05:07:13 INFO - PROCESS | 1660 | colorDepth:24 05:07:13 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 05:07:13 INFO - PROCESS | 1660 | __metrics 05:07:13 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 05:07:13 INFO - TEST-INFO | 1660: exit 0 05:07:13 INFO - Browser initialized. 05:07:13 INFO - Running cycle 1/1 for dromaeo_css test... 05:07:13 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpq68FgF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:25 INFO - PROCESS | 1663 | RSS: Main: 217890816 05:07:25 INFO - PROCESS | 1663 | 05:08:15 INFO - PROCESS | 1663 | 1460030895345 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] 05:10:26 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 05:10:27 INFO - PROCESS | 1663 | RSS: Main: 229691392 05:10:27 INFO - PROCESS | 1663 | 05:13:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 05:13:30 INFO - PROCESS | 1663 | RSS: Main: 240136192 05:13:30 INFO - PROCESS | 1663 | 05:16:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 05:16:32 INFO - PROCESS | 1663 | RSS: Main: 242991104 05:16:32 INFO - PROCESS | 1663 | 05:19:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 05:19:34 INFO - PROCESS | 1663 | RSS: Main: 243736576 05:19:34 INFO - PROCESS | 1663 | 05:22:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 05:22:38 INFO - PROCESS | 1663 | RSS: Main: 246562816 05:22:38 INFO - PROCESS | 1663 | 05:25:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 05:25:40 INFO - PROCESS | 1663 | RSS: Main: 248160256 05:25:40 INFO - PROCESS | 1663 | 05:25:40 INFO - PROCESS | 1663 | __start_tp_report 05:25:40 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 05:25:40 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 05:25:40 INFO - PROCESS | 1663 | |i|pagename|runs| 05:25:40 INFO - PROCESS | 1663 | |0;dojo.html;3161.8735250589975;3276.7542434317425;3588.7308451866115;3645.4896314515954;3651.3245049665816;5914.822555323336;5916.083007133896;5917.9408205917925;5927.436893495111;5948.167256584076;5954.225950626413;5983.790567330134;6010.2186715727;6018.39816018398;6019.7893073742425;5297.390800058004;5302.284191634129;5325.4142044375085;5368.946310536884;5418.7019713915715;5282.577393808505;5422.349318081816;5435.483629055253;5477.577198573424;5517.779288828442;5344.866378340534;5351.464853514656;5354.973225133869;5356.089464790975;5375.865603359908;4810.254410566367;5251.632385733;5360.65155764874;5417.83746487606;5429.782808687648;5267;5342.439043900374;5357.919631205535;5363.249145119687;5401.459854014606;5928.6739229342365;5975.701214939236;5979.983402821531;5999.910001349983;6033.668148251844;6107.583301036475;6185;6220.129181914535;6230.906536401958;6260.092286618415;6278.591891527036;6289.905651415233;6342.87314253717;6345.333739957284;6359.968200159016;5919.733611987471;6080.361562036011;6081.847953801146;6083.7566497340495;6108.267007959029;6353.4599559038;6417.26201486835;6431.614103153732;6442.871142577123;6448.226212854487;5543.805966791224;5899.852503687356;6204.852102360987;6212.4408803207025;6215.160953271251;6159.384061593894;6208.4722798561725;6289.905651415233;6292.8426789329715;6295;6163.84590385244;6224.097505861715;6227.221597300338;6233.937660623426;6253.812385628438;6170.50635949123;6228.750849966042;6233.3143354231;6238.968805156036;6250.124982502362;4897.094037602984;4974.8756281093265;5008.69947803133;5027.874303142377;5040.747962601855;4494.70784399013;4495.190865644216;4497.572730590588;4516.458025037016;4528.252838281713;5812.808374283261;6025.0359942409;6042.154098426224;6062.545309101835;6075.422834830684;5154.433012368637;6265.96867015662;6305.4009869062365;6311.495080393557;6332.1135041094285;5124.897502050013;5844.88310233793;6030.6080104793045;6041.909371359433;6115.052166914135;5500.944990550043;5540.058190107691;5546.140348246028;5579.804706835242;5580.693061881636;6340.556161068773;6463;6529.9020514691365;6584.374484423972;6585.374389432996;5465.797524544594;6294.842628934313;6305.464035557029;6310.6213627182515;6315.210598675166;6162.235583165155;6528.706208220737;6541.901871471837;6547.410733034049;6551.4103730664465;5230.241614965733;6171.352008039271;6174.413430724074;6202.038684003986;6250;6310.05349197624;6331.620102793847;6338.239411270677;6340.175777148941;6381.8085457436355;5683.687397193194;5889.764409423747;6209.037599171955;6244;6271.216097987752;6093.238345206849;6128.258691089361;6147.047207682816;6151.477124444473;6158.907616385668;5472.015037293326;5616.494515493625;6084.11780291864;6181.81454556364;6224.782132625336;6140.293866205444;6168.506519478341;6209.565330426827;6230.563860529719;6243.37566243372;5299.284596579567;6069.575129740876;6206.72069756853;6222.719977600928;6235.75056997715 05:25:40 INFO - PROCESS | 1663 | |1;ext.html;459.27663929311325;515.6983164848564;617.2716194890028;643.3662842100531;665.2515919590459;29134.708652913465;36502.627329223615;37832.81083594581;38480.03799905004;38793.41809872854;39458.81623551291;39643.00892477689;39931.201375972465;39969.60030399695;40059;9351.76620584484;9546.427214367126;9559.904400956006;9564.234861211087;9594.76013099671;7911.2088791121005;8090.959545202266;9617.326787124905;9627.807443851154;9662.951685241564;9556.80886382272;9633.084856938614;9648.131668149901;9650.71047868565;9663;9559.522023898777;9564.760880977961;9598.040195980417;9618.374805637646;9656.08267214617;8483.957580212122;9519;9536.046395360478;9678.370905891095;9697.612095516171;33419.49870751941;39343.803280983695;39375.21249575021;39599;39816.80549583517;3034.5599888016336;3196.9130495631366;3215.4212241796467;3265.96734032659;3395.1172695099203;22325.44186395337;26675.46649067027;26895.731042689713;26911.192664220103;27375.04187353433;2245.854019488744;2296.2651951375565;2316.7219933607907;2778.1665500349814;2867.426514697069;238217;285498;298275.5086224555;310133.4493327519;321525;47120.05759884783;47130;47462.288065679044;56457.717711412;64105.71788564203;46085.539144608796;46097;46127.30809037867;46302.53697463049;46403.83990400267;16627.418040368528;16789.496315110566;16801.66396672084;16808.991460512163;16820.327186912633;13195.20828750278;13274.800877986838;13351;13420.993425493127;13511.594652160451;15290;15323.846761532464;15371.385144594315;15404;15405.306761195772;6438.806835794933;6586.110875031809;6592.901106483407;6616.338366163345;6625.370589794058;1009.2178561614808;1029.6447725534679;1038.3406536849154;1043.4521876015153;1047.1099565369375;30136.24659383489;30330;30388.84805575958;30761.69238307633;30800;1026.947378936596;1160.2400427719856;1192.65413030222;1238.6407941696807;1247.8253044573767;14711.411543538352;15298.617534561725;15408.614784630474;15690.764638530203;15742.685146297239;502796;719403;736742;738236;739241;59953.70023149944;60267.095993560135;60460.395396046355;60968.695156524824;61048.38951610427;1513.9091654500764;1514.9318280677176;1518.2788175616715;1521.4598817419878;1525.0544662309217;28795.992140275826;29623.703762962094;29971.55042674318;30270.789168433897;30576;51186;51194.23208651948;51253.48746512487;51600.74199628978;51622.48377516327;459464;473403;473933;474006;475403;32831;33232.33535329328;33236.1690957728;33430.49854252237;33613.15967100842;44332.556674434134;47701;48284.27573586469;48942.02115957732;49215.76960576014;37637;38428;38429.423558646085;38532.61467385403;38586.42120368253;37297.81351093227;37411;37653.7519946877;38021.61978380291;38095;33174.83412582921;37151;38835.83492495117;40037.39943900902;40587.797061014506;30937.69062309348;32385.35229295448;33782;33939.830300848334;34111.14722131967;25082.74917250804;25335.87332063328;25491.10781122726;25707.485850283265;25804.483910322066 05:25:40 INFO - PROCESS | 1663 | |2;jquery.html;368.6184798733312;370.82818294190366;379.67537755219286;385.7126440801603;390.5313623651615;16652.83347166528;20222.494437639067;20472.385828425133;20973.265935692234;21032.579348413023;17959;21416.8929155354;21450.24924127652;21666.35000949974;21732.34802955914;5604.1593760935775;5923.526117910578;5931.762729490837;5932;5992.310884248302;5508.91736623949;5822.097574875901;5828.154917536955;5838.036723940545;5839.649621022731;5655.915161272585;5731.484166425021;5743.741531631065;5764.308283006045;5777.62445441045;5264.552513036378;5736.196932429484;5753.367129615739;5753.856153596152;5755.39568345324;4940.209566469383;4991.825286115005;5039.193729003378;5048;5056.595472362203;25948.87025564879;25988.09041683551;26308.21075367742;26459.073932412273;26742;2339.4034521196986;2356.2342238772294;2415.987920060406;2635.683717953839;2920.8977685780897;9504.002079781661;10049.045340692623;14756.631084222872;14853.108813471119;15181.6204594886;1704.326790917593;1774.157275294225;1776.111944027986;1810.7283907413862;1818.5999080202466;87120.49182367996;90152.54923725405;93149.60275595804;93176.53411732966;94234;11423.428828558539;11465.369404682822;11490.195686301879;11658.06735461161;11716.297022178696;14030.947678924122;14138.50515231962;14149.646258843406;14198.85801141996;14269.64325891861;7505.587192704454;8872.911270887338;8979.416337938144;9031.729048128567;9118.772030699285;9198.540072996457;9259.259259259243;9275.61661316374;9284.535773211312;9369.063093690713;4457.68796184264;4523.344115103291;4551.5676010778925;4562.790860421991;4576.656750743708;1895.4503194073563;1951.365806113019;1953.687410014391;1978.851586131046;1981.603679264153;828.362161135922;907.8865141857268;995.3629677006721;1029.1818004686293;1043.3270540501485;19580;20608.089539560737;20622.896885515478;20665.79334206669;20958.47603809917;1003.087190656499;1026.1790567545934;1051.323139592349;1074.6238816414193;1081.302559848893;10548.683539493828;11091.889081109248;11112.944435277934;11126.554937802559;11157.72105697364;480799;490477;491466;580424;581543;100432;104620.47689761502;106518;112033;125347;1046.1735229168871;1089.7874914391618;1097.4567589043475;1097.665212110314;1098.6868742408535;13735.793963090762;17579.472615821545;17913.283468661106;17963.730544041588;18302.81697183011;51568.74215628898;52688.73655631697;52738.47261527336;52872.206916895506;53860;79322.6033869827;80014.19985800068;80114;81309.78035329594;81505.59247203726;7925.881111783443;8072.959635201786;8151.592420378839;8174.67301307941;8442.873356899774;9425.340226184102;12647.304398258184;13041.673958151123;13424.597262082154;13620.659483512594;11225.943870280924;11368.94315528417;11539.07687385024;11639.418029098342;11859.940700296444;11442.141839361915;11563.190576659554;11730.178887478136;11801.645950621494;11851.644450666494;11905;11987.22083064598;12064.819027714415;12067.336296503425;12208.206466579644;8204.507729536246;8311.96100487439;8319.708810191614;8345.33237340999;8383.62273697673;8545.74362769118;11194.216404851582;11955.462004209658;11972;12011.159218854596 05:25:40 INFO - PROCESS | 1663 | |3;mootools.html;1378.8000739892714;1419.666378401076;1447.5729659750373;1601.5755824706455;1691.196681576252;8341.749747507569;8985.730428087152;9095.408798428098;9247.90752092479;9276.11876871697;8048.758537243877;8762.992255890575;9021.503817290046;9137.725868223948;9155.450672959627;2540.2760213339157;2562.1544890186287;2566.7304932982047;2574.3306740247485;2577.6004719268544;2516.7105782834933;2586.314626623937;2595.7144714081437;2597.935051623715;2606.439615482671;2546.1088618983395;2574.2792018234923;2606.5177942080777;2612.4644447888136;2614.9670880002386;2188.4638263625357;2214.9921785587517;2554;2590.676165479315;2604.0625374865035;2567.0245306783304;2573.974260257392;2600.388908606476;2604.2577865308485;2609.686837579484;9110.36227464071;9199.080091990814;9211.032841551674;9212.815743685089;9281.97898231194;5630.915536266959;5703.828885133453;5745.885082298372;5802;5820.650760954314;3609.3322735294055;3696.6488183622514;3701.148735790783;3756.9624303756887;3791.203847192093;1048.26621365044;1067.1036329483272;1070.1409781446596;1075.4353964168795;1108.545496346494;10718.196135289885;12348.876511234952;12407.069469789802;12449.377531123499;12471.939885109687;5055.545000949932;5085.516875896784;5100.566451851634;5153.974230128825;5173.456787037382;10656.147508199325;10698.03717665414;10700.946495267473;10716.571337146426;10740.087092597218;5735.311762588516;5844.473997340175;5844.824655260349;5857.648541087463;5860.589758716934;4169.332906734908;4657.74382408964;5142.794288228504;5159.071367153948;5217.973910130501;4121.052158003676;4126.429320417413;4170.457840480718;4177.665786737054;4197.706160568792;1351.3918736568387;1380.6548362909273;1380.8757211850982;1384.439302082638;1390.7705228637365;1397.0988712280523;1412.0750908155176;1429.3639330497829;1440.942362305517;1476.018447732246;7538.698452061856;8272.503649780912;8495.277901378453;8551.102134275936;8632.093630168867;522.2010324203974;523.5314393617767;532.2176400690978;532.3452153850785;532.5473347654463;6363.045543168561;6370.713317900706;6415.037744338387;6428.935710642927;6449.516286278548;10960.451977401099;11397.772044559062;11436.542538298374;11445.313281203154;11477;10856.511456984455;11092.556297748;11158.330500170016;11214.775704485864;11221;8700.52147131901;8743.125687431333;8804.163604457566;8840.292776577859;8853.424527405698;6934.549254298455;7020.859582808213;8105;8204.671813127408;8316.501009939422;8367.95816020916;9720.951395242979;9896.010398960047;10073.445960472245;10206.387616743019;10217.284790064921;10546.26176167661;10547.314424562379;10582.470876455993;10636.30863993838;7800.219978002381;7848.725294614661;7866.410019248694;7933;7952.681892724227;8507.95746021266;8591.613377397907;8633.481991080555;8646.913530864862;8684.609192586222;6550.672466376757;7543.35881450061;7650.464467487445;7713.336752630372;7837.451378403457;7511.436642251962;7629.351505122127;7649.120351159465;7745.419093567893;7775.028121484815;8116.391270654606;8127.1060183378595;8175.754727358189;8197.89328440653;8298.58507074656;6254.718537665725;7288.453365997465;7412.703491860265;7420.072490938633;7444.925550744423;7523.360514356341;7544.811379715551;7556.677581423339;7567.508111972704;7585.734499292498 05:25:40 INFO - PROCESS | 1663 | |4;prototype.html;330.51084395095256;332.6523782647134;332.7388000419671;337.84625503904164;339.2858033838768;6924.930750692498;6929.896051559218;6942.548734332271;6951.791446256608;6959.373656370925;6781.728730850772;6844.931550684491;6899.3445622665895;6915.170179578445;6918.135233095863;4864.516322521629;4894.804207831682;4980.626453016038;5026.52248036436;5071.644984851071;4057.3102572562734;4963.677360971544;4970.378702662168;4986.626003049768;5021.9748901255625;4922.310876477296;4933.939203071339;4938.580220681247;4941.555260026596;4964.106460837048;4937.456879743226;4953.108440480712;4961.107000739866;4962.354893863775;4966.255061740731;4271.145770845843;4289.892752681177;4291.420658211133;4297.119090586422;4318.805653745589;6342.714577844008;6616.702248398834;6633.9668301658185;6642.800715978528;6690.531662783607;5871.14868344092;5913.733881975321;5916.437938395846;5926.170336152942;5927.673977931212;7384.889226661604;7414.073240844895;7420.146683131401;7424.777256682308;7441.479096463249;4688.859334219979;4691.671582989192;4696.319033740123;4698.718076915396;4710;5131.769070391804;7768.028996375453;7849.607519623996;7869.645865936047;7871.094824095245;4570.497245303014;4630.004549021909;4687.882802929954;4704.223803072455;4758.191107511731;4839.806407743651;4840.491748366441;4842.201036828954;4846.39420072491;4859.514048595112;5822.417758224144;5867.061270196833;5889.204957330791;5892.823215303548;5896.321922978912;7408.888866667005;7418.666160022812;7438.070241219848;7438.5908775016815;7466.775996720107;1657.883948123644;1685.9662806743797;1686.5783554111472;1689.9662006759795;1691.3319238900565;1012.8251228574965;1056.4944953441316;1065.9200559958035;1067.4929408530886;1067.9839802402971;6773.221079575814;6832.145981752281;6875.828104297432;6886.586804791728;6909.619970901548;7539.698412063456;7631.3513351365755;7636.656350464243;7639.350655194259;7656.310932016034;6819.318068193141;6830.214525329551;6849.280825513349;6857.108575885203;6862.588244705234;4023.939640905389;4047.8583249586122;4067.247559201557;4076.062505623723;4079.918401631951;7350.70597176107;7834.960825195838;8146.289279251424;8151.347892168612;8151.429399941947;8000;8005.479643823133;8009.559474228973;8020.959895200486;8056.194380562014;7305.305995930378;8105.230003149692;8108.797280067927;8133.877991830127;8155.143709910374;7544.585047822423;7703.922960770321;7783.455158138876;7793.532388056735;7823.100343460575;7582.165961744094;7591.4685971981435;7625.580593067212;7633.618319084135;7653.617319133909;6591.406773390418;8133.055468097849;8148.877766833383;8153.7553873383895;8161.836763264821;4778.59381952524;4821.975890120527;4828.975855120702;4844.927326090182;4852.757362131809;7376.299251571092;7505.549667019996;7655;7682.154962954182;7768.689252429839;7473.252674732484;7499.325060744341;7505.924940750741;7516.586587737508;7541.622918854145;6117.755289788359;7431.628418578941;7460.95546623484;7480.027596412431;7504.437167212372;5952.642841429528;6619.933800661932;6699.866002680016;6985.790426287016;7328.926710732824;6085.878282434415;7420.888686669597;7497.250274972459;7499.100107987077;7500.662470188746;7308.415326773952;7328.96335518319;7347.448941329529;7354.411647068115;7373.078365204349 05:25:40 INFO - PROCESS | 1663 | |5;yui.html;670.9127813384259;695.4401706626167;715.2311265389706;746.1046743907312;750.5046669198326;14995.875309351815;15116.470923517665;15209.54371368858;15292.006019608718;15358.616034599141;14328.140311581312;14448.422063117489;14491.34788934498;14501.637459063528;14523.927380363084;802.297989259398;896.5786080542151;983.7589790501302;995.5719040812447;1010.7271036820055;993.0913214408807;997.4962643864874;1000.3997601439115;1003.1874181912674;1004.1364426593124;1021.075926286708;1046.481991414251;1048.8951104889488;1051.148569658575;1052.2318707343643;920.8581359114717;1000.5447521377829;1021.6628512590864;1034.4414016431117;1040.8907064758166;746.9663865126082;765.0169532151215;772.5557804262571;776.7048521561827;780.8204113053972;15183.696326073417;15253.847461525465;15463.845361546466;15547.455839045579;15563.533094007198;13159.671008224776;13206.075574709774;13217.801732974012;13225.86774132256;13311.733765324736;10207.693769186862;10270.589176432934;10307.381557106597;10390.168786497063;10432.061114499731;3498.3702933471964;3516.4197907345515;3549.680528752399;3613.5483064616924;3782.111203867089;65883.352916177;69073.61852762967;79015;79623;79962.60018699928;9657.90342096584;9663.985281545478;9690.903090969001;9798.853017204749;9935.552900119512;22199.77800221977;22376.664350034764;22397.10411583551;22399.552008960054;22425.66361504579;7321.853562928818;7401.851962960711;7416.332530072319;7437.51656142349;7491.775246742606;4084.81618327176;4135.255653982312;4172.979135104305;4240.194363071007;4308.138372325548;3712.4988126603103;3729.7202709796875;3762.3792074307435;3766.37854753968;3768.962310376916;652.9934700653027;656.8029591122644;659.561391674531;668.3717305732692;686.993130068693;499.68270148455264;503.54177698294376;504.2738456622452;614.1034090228228;623.2271982741368;7116.964415177891;7723.729669461654;7865.938098356649;8192.385571082194;8380.958095209608;523.1472699499791;603.405645439242;609.7896225802091;612.2897438970773;614.4163045107166;3758.285925674113;3792.27946690128;3823.6941044716355;3848.826802793825;4025.577314382006;90461;90461.54769226111;90805;95837;96217;77887;78641.82037269445;79550;82184.58907705423;86254;63296.367036329044;64009.679951599945;64145.35854641394;64717.70564588682;68952;7705.653245603849;7817.413693972861;7866.370690344873;7875.763727088197;8077.571837789793;50766.7308317295;50959.980800384525;51138.488615113376;52106.218406724685;53175.46824531705;66124.6693766528;69161;71716.64141679258;74162.62918685372;75322.87015694659;10207.183425325808;10343.534540945526;10765.892341076802;10951.313101111215;11167.609133680282;12049.33728644933;12057.15599907998;12078.577249796215;12080;12592.118551701293;11330.546778128783;11397.772044558897;11449.942750286194;11450.370229637118;12181.939090304491;11216.495257713586;11254.493547790533;11259.211855170059;11538.423078846192;12161.087918406332;11052.502637381176;11112.222144450134;11280.49237784318;11341.432928353384;11403.087752979907;9360.15758581731;9445.858312125461;9671.613135474503;9697.806043879224;9700;8534.658613655385;8746.300295976434;8754.430961987451;9216.539173041456;9632.566534505824 05:25:40 INFO - PROCESS | 1663 | __end_tp_report 05:25:40 INFO - PROCESS | 1663 | __start_cc_report 05:25:40 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,362 05:25:40 INFO - PROCESS | 1663 | __end_cc_report 05:25:40 INFO - PROCESS | 1663 | __startTimestamp1460031940676__endTimestamp 05:25:40 INFO - PROCESS | 1663 | 05:25:40 INFO - PROCESS | 1663 | ------- Summary: start ------- 05:25:40 INFO - PROCESS | 1663 | Number of tests: 6 05:25:40 INFO - PROCESS | 1663 | 05:25:40 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5834.14 Median:6081.85 stddev:640.47 (10.5%) stddev-sans-first:607.16 05:25:40 INFO - PROCESS | 1663 | Values: 3161.9 3276.8 3588.7 3645.5 3651.3 5914.8 5916.1 5917.9 5927.4 5948.2 5954.2 5983.8 6010.2 6018.4 6019.8 5297.4 5302.3 5325.4 5368.9 5418.7 5282.6 5422.3 5435.5 5477.6 5517.8 5344.9 5351.5 5355.0 5356.1 5375.9 4810.3 5251.6 5360.7 5417.8 5429.8 5267.0 5342.4 5357.9 5363.2 5401.5 5928.7 5975.7 5980.0 5999.9 6033.7 6107.6 6185.0 6220.1 6230.9 6260.1 6278.6 6289.9 6342.9 6345.3 6360.0 5919.7 6080.4 6081.8 6083.8 6108.3 6353.5 6417.3 6431.6 6442.9 6448.2 5543.8 5899.9 6204.9 6212.4 6215.2 6159.4 6208.5 6289.9 6292.8 6295.0 6163.8 6224.1 6227.2 6233.9 6253.8 6170.5 6228.8 6233.3 6239.0 6250.1 4897.1 4974.9 5008.7 5027.9 5040.7 4494.7 4495.2 4497.6 4516.5 4528.3 5812.8 6025.0 6042.2 6062.5 6075.4 5154.4 6266.0 6305.4 6311.5 6332.1 5124.9 5844.9 6030.6 6041.9 6115.1 5500.9 5540.1 5546.1 5579.8 5580.7 6340.6 6463.0 6529.9 6584.4 6585.4 5465.8 6294.8 6305.5 6310.6 6315.2 6162.2 6528.7 6541.9 6547.4 6551.4 5230.2 6171.4 6174.4 6202.0 6250.0 6310.1 6331.6 6338.2 6340.2 6381.8 5683.7 5889.8 6209.0 6244.0 6271.2 6093.2 6128.3 6147.0 6151.5 6158.9 5472.0 5616.5 6084.1 6181.8 6224.8 6140.3 6168.5 6209.6 6230.6 6243.4 5299.3 6069.6 6206.7 6222.7 6235.8 05:25:40 INFO - PROCESS | 1663 | 05:25:40 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:62180.20 Median:38058.31 stddev:137639.34 (361.7%) stddev-sans-first:137947.45 05:25:40 INFO - PROCESS | 1663 | Values: 459.3 515.7 617.3 643.4 665.3 29134.7 36502.6 37832.8 38480.0 38793.4 39458.8 39643.0 39931.2 39969.6 40059.0 9351.8 9546.4 9559.9 9564.2 9594.8 7911.2 8091.0 9617.3 9627.8 9663.0 9556.8 9633.1 9648.1 9650.7 9663.0 9559.5 9564.8 9598.0 9618.4 9656.1 8484.0 9519.0 9536.0 9678.4 9697.6 33419.5 39343.8 39375.2 39599.0 39816.8 3034.6 3196.9 3215.4 3266.0 3395.1 22325.4 26675.5 26895.7 26911.2 27375.0 2245.9 2296.3 2316.7 2778.2 2867.4 238217.0 285498.0 298275.5 310133.4 321525.0 47120.1 47130.0 47462.3 56457.7 64105.7 46085.5 46097.0 46127.3 46302.5 46403.8 16627.4 16789.5 16801.7 16809.0 16820.3 13195.2 13274.8 13351.0 13421.0 13511.6 15290.0 15323.8 15371.4 15404.0 15405.3 6438.8 6586.1 6592.9 6616.3 6625.4 1009.2 1029.6 1038.3 1043.5 1047.1 30136.2 30330.0 30388.8 30761.7 30800.0 1026.9 1160.2 1192.7 1238.6 1247.8 14711.4 15298.6 15408.6 15690.8 15742.7 502796.0 719403.0 736742.0 738236.0 739241.0 59953.7 60267.1 60460.4 60968.7 61048.4 1513.9 1514.9 1518.3 1521.5 1525.1 28796.0 29623.7 29971.6 30270.8 30576.0 51186.0 51194.2 51253.5 51600.7 51622.5 459464.0 473403.0 473933.0 474006.0 475403.0 32831.0 33232.3 33236.2 33430.5 33613.2 44332.6 47701.0 48284.3 48942.0 49215.8 37637.0 38428.0 38429.4 38532.6 38586.4 37297.8 37411.0 37653.8 38021.6 38095.0 33174.8 37151.0 38835.8 40037.4 40587.8 30937.7 32385.4 33782.0 33939.8 34111.1 25082.7 25335.9 25491.1 25707.5 25804.5 05:25:40 INFO - PROCESS | 1663 | 05:25:40 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:31923.72 Median:2386.11 stddev:87615.30 (3671.9%) stddev-sans-first:87829.05 05:25:40 INFO - PROCESS | 1663 | Values: 368.6 370.8 379.7 385.7 390.5 16652.8 20222.5 20472.4 20973.3 21032.6 17959.0 21416.9 21450.2 21666.4 21732.3 5604.2 5923.5 5931.8 5932.0 5992.3 5508.9 5822.1 5828.2 5838.0 5839.6 5655.9 5731.5 5743.7 5764.3 5777.6 5264.6 5736.2 5753.4 5753.9 5755.4 4940.2 4991.8 5039.2 5048.0 5056.6 25948.9 25988.1 26308.2 26459.1 26742.0 2339.4 2356.2 2416.0 2635.7 2920.9 9504.0 10049.0 14756.6 14853.1 15181.6 1704.3 1774.2 1776.1 1810.7 1818.6 87120.5 90152.5 93149.6 93176.5 94234.0 11423.4 11465.4 11490.2 11658.1 11716.3 14030.9 14138.5 14149.6 14198.9 14269.6 7505.6 8872.9 8979.4 9031.7 9118.8 9198.5 9259.3 9275.6 9284.5 9369.1 4457.7 4523.3 4551.6 4562.8 4576.7 1895.5 1951.4 1953.7 1978.9 1981.6 828.4 907.9 995.4 1029.2 1043.3 19580.0 20608.1 20622.9 20665.8 20958.5 1003.1 1026.2 1051.3 1074.6 1081.3 10548.7 11091.9 11112.9 11126.6 11157.7 480799.0 490477.0 491466.0 580424.0 581543.0 100432.0 104620.5 106518.0 112033.0 125347.0 1046.2 1089.8 1097.5 1097.7 1098.7 13735.8 17579.5 17913.3 17963.7 18302.8 51568.7 52688.7 52738.5 52872.2 53860.0 79322.6 80014.2 80114.0 81309.8 81505.6 7925.9 8073.0 8151.6 8174.7 8442.9 9425.3 12647.3 13041.7 13424.6 13620.7 11225.9 11368.9 11539.1 11639.4 11859.9 11442.1 11563.2 11730.2 11801.6 11851.6 11905.0 11987.2 12064.8 12067.3 12208.2 8204.5 8312.0 8319.7 8345.3 8383.6 8545.7 11194.2 11955.5 11972.0 12011.2 05:25:40 INFO - PROCESS | 1663 | 05:25:40 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:6284.27 Median:5195.72 stddev:3346.68 (64.4%) stddev-sans-first:3335.75 05:25:40 INFO - PROCESS | 1663 | Values: 1378.8 1419.7 1447.6 1601.6 1691.2 8341.7 8985.7 9095.4 9247.9 9276.1 8048.8 8763.0 9021.5 9137.7 9155.5 2540.3 2562.2 2566.7 2574.3 2577.6 2516.7 2586.3 2595.7 2597.9 2606.4 2546.1 2574.3 2606.5 2612.5 2615.0 2188.5 2215.0 2554.0 2590.7 2604.1 2567.0 2574.0 2600.4 2604.3 2609.7 9110.4 9199.1 9211.0 9212.8 9282.0 5630.9 5703.8 5745.9 5802.0 5820.7 3609.3 3696.6 3701.1 3757.0 3791.2 1048.3 1067.1 1070.1 1075.4 1108.5 10718.2 12348.9 12407.1 12449.4 12471.9 5055.5 5085.5 5100.6 5154.0 5173.5 10656.1 10698.0 10700.9 10716.6 10740.1 5735.3 5844.5 5844.8 5857.6 5860.6 4169.3 4657.7 5142.8 5159.1 5218.0 4121.1 4126.4 4170.5 4177.7 4197.7 1351.4 1380.7 1380.9 1384.4 1390.8 1397.1 1412.1 1429.4 1440.9 1476.0 7538.7 8272.5 8495.3 8551.1 8632.1 522.2 523.5 532.2 532.3 532.5 6363.0 6370.7 6415.0 6428.9 6449.5 10960.5 11397.8 11436.5 11445.3 11477.0 10856.5 11092.6 11158.3 11214.8 11221.0 8700.5 8743.1 8804.2 8840.3 8853.4 6934.5 7020.9 8105.0 8204.7 8316.5 8368.0 9721.0 9896.0 10073.4 10206.4 10217.3 10546.3 10547.3 10582.5 10636.3 7800.2 7848.7 7866.4 7933.0 7952.7 8508.0 8591.6 8633.5 8646.9 8684.6 6550.7 7543.4 7650.5 7713.3 7837.5 7511.4 7629.4 7649.1 7745.4 7775.0 8116.4 8127.1 8175.8 8197.9 8298.6 6254.7 7288.5 7412.7 7420.1 7444.9 7523.4 7544.8 7556.7 7567.5 7585.7 05:25:40 INFO - PROCESS | 1663 | 05:25:40 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:6012.08 Median:6831.18 stddev:1963.59 (28.7%) stddev-sans-first:1922.24 05:25:40 INFO - PROCESS | 1663 | Values: 330.5 332.7 332.7 337.8 339.3 6924.9 6929.9 6942.5 6951.8 6959.4 6781.7 6844.9 6899.3 6915.2 6918.1 4864.5 4894.8 4980.6 5026.5 5071.6 4057.3 4963.7 4970.4 4986.6 5022.0 4922.3 4933.9 4938.6 4941.6 4964.1 4937.5 4953.1 4961.1 4962.4 4966.3 4271.1 4289.9 4291.4 4297.1 4318.8 6342.7 6616.7 6634.0 6642.8 6690.5 5871.1 5913.7 5916.4 5926.2 5927.7 7384.9 7414.1 7420.1 7424.8 7441.5 4688.9 4691.7 4696.3 4698.7 4710.0 5131.8 7768.0 7849.6 7869.6 7871.1 4570.5 4630.0 4687.9 4704.2 4758.2 4839.8 4840.5 4842.2 4846.4 4859.5 5822.4 5867.1 5889.2 5892.8 5896.3 7408.9 7418.7 7438.1 7438.6 7466.8 1657.9 1686.0 1686.6 1690.0 1691.3 1012.8 1056.5 1065.9 1067.5 1068.0 6773.2 6832.1 6875.8 6886.6 6909.6 7539.7 7631.4 7636.7 7639.4 7656.3 6819.3 6830.2 6849.3 6857.1 6862.6 4023.9 4047.9 4067.2 4076.1 4079.9 7350.7 7835.0 8146.3 8151.3 8151.4 8000.0 8005.5 8009.6 8021.0 8056.2 7305.3 8105.2 8108.8 8133.9 8155.1 7544.6 7703.9 7783.5 7793.5 7823.1 7582.2 7591.5 7625.6 7633.6 7653.6 6591.4 8133.1 8148.9 8153.8 8161.8 4778.6 4822.0 4829.0 4844.9 4852.8 7376.3 7505.5 7655.0 7682.2 7768.7 7473.3 7499.3 7505.9 7516.6 7541.6 6117.8 7431.6 7461.0 7480.0 7504.4 5952.6 6619.9 6699.9 6985.8 7328.9 6085.9 7420.9 7497.3 7499.1 7500.7 7308.4 7329.0 7347.4 7354.4 7373.1 05:25:40 INFO - PROCESS | 1663 | 05:25:40 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18292.27 Median:25635.50 stddev:25542.01 (99.6%) stddev-sans-first:25579.39 05:25:40 INFO - PROCESS | 1663 | Values: 670.9 695.4 715.2 746.1 750.5 14995.9 15116.5 15209.5 15292.0 15358.6 14328.1 14448.4 14491.3 14501.6 14523.9 802.3 896.6 983.8 995.6 1010.7 993.1 997.5 1000.4 1003.2 1004.1 1021.1 1046.5 1048.9 1051.1 1052.2 920.9 1000.5 1021.7 1034.4 1040.9 747.0 765.0 772.6 776.7 780.8 15183.7 15253.8 15463.8 15547.5 15563.5 13159.7 13206.1 13217.8 13225.9 13311.7 10207.7 10270.6 10307.4 10390.2 10432.1 3498.4 3516.4 3549.7 3613.5 3782.1 65883.4 69073.6 79015.0 79623.0 79962.6 9657.9 9664.0 9690.9 9798.9 9935.6 22199.8 22376.7 22397.1 22399.6 22425.7 7321.9 7401.9 7416.3 7437.5 7491.8 4084.8 4135.3 4173.0 4240.2 4308.1 3712.5 3729.7 3762.4 3766.4 3769.0 653.0 656.8 659.6 668.4 687.0 499.7 503.5 504.3 614.1 623.2 7117.0 7723.7 7865.9 8192.4 8381.0 523.1 603.4 609.8 612.3 614.4 3758.3 3792.3 3823.7 3848.8 4025.6 90461.0 90461.5 90805.0 95837.0 96217.0 77887.0 78641.8 79550.0 82184.6 86254.0 63296.4 64009.7 64145.4 64717.7 68952.0 7705.7 7817.4 7866.4 7875.8 8077.6 50766.7 50960.0 51138.5 52106.2 53175.5 66124.7 69161.0 71716.6 74162.6 75322.9 10207.2 10343.5 10765.9 10951.3 11167.6 12049.3 12057.2 12078.6 12080.0 12592.1 11330.5 11397.8 11449.9 11450.4 12181.9 11216.5 11254.5 11259.2 11538.4 12161.1 11052.5 11112.2 11280.5 11341.4 11403.1 9360.2 9445.9 9671.6 9697.8 9700.0 8534.7 8746.3 8754.4 9216.5 9632.6 05:25:40 INFO - PROCESS | 1663 | -------- Summary: end -------- 05:25:40 INFO - PROCESS | 1663 | 05:25:41 INFO - TEST-INFO | 1663: exit 0 05:25:41 INFO - TEST-OK | dromaeo_css | took 1110879ms 05:25:41 INFO - TEST-START | kraken 05:25:41 INFO - operating with platform_type : mac_ 05:25:41 INFO - Initialising browser for kraken test... 05:25:41 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpCJzNYk/profile http://localhost:49266/getInfo.html) 05:25:43 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 05:25:43 INFO - PROCESS | 1675 | colorDepth:24 05:25:43 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 05:25:43 INFO - PROCESS | 1675 | __metrics 05:25:43 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 05:25:44 INFO - TEST-INFO | 1675: exit 0 05:25:44 INFO - Browser initialized. 05:25:44 INFO - Running cycle 1/1 for kraken test... 05:25:44 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpCJzNYk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:56 INFO - PROCESS | 1678 | RSS: Main: 217899008 05:25:56 INFO - PROCESS | 1678 | 05:25:59 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:59 INFO - PROCESS | 1678 | RSS: Main: 230195200 05:25:59 INFO - PROCESS | 1678 | 05:26:02 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 05:26:02 INFO - PROCESS | 1678 | RSS: Main: 239861760 05:26:02 INFO - PROCESS | 1678 | 05:26:06 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 05:26:06 INFO - PROCESS | 1678 | RSS: Main: 243240960 05:26:06 INFO - PROCESS | 1678 | 05:26:08 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 05:26:09 INFO - PROCESS | 1678 | RSS: Main: 243789824 05:26:09 INFO - PROCESS | 1678 | 05:26:11 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:26:12 INFO - PROCESS | 1678 | RSS: Main: 245284864 05:26:12 INFO - PROCESS | 1678 | 05:26:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 05:26:15 INFO - PROCESS | 1678 | RSS: Main: 244674560 05:26:15 INFO - PROCESS | 1678 | 05:26:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 05:26:18 INFO - PROCESS | 1678 | RSS: Main: 245080064 05:26:18 INFO - PROCESS | 1678 | 05:26:21 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 05:26:22 INFO - PROCESS | 1678 | RSS: Main: 245248000 05:26:22 INFO - PROCESS | 1678 | 05:26:24 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:24 INFO - PROCESS | 1678 | RSS: Main: 244613120 05:26:24 INFO - PROCESS | 1678 | 05:26:26 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:27 INFO - PROCESS | 1678 | RSS: Main: 244699136 05:26:27 INFO - PROCESS | 1678 | 05:26:29 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:30 INFO - PROCESS | 1678 | RSS: Main: 245469184 05:26:30 INFO - PROCESS | 1678 | 05:26:32 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:33 INFO - PROCESS | 1678 | RSS: Main: 245768192 05:26:33 INFO - PROCESS | 1678 | 05:26:35 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:36 INFO - PROCESS | 1678 | RSS: Main: 245428224 05:26:36 INFO - PROCESS | 1678 | 05:26:37 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 05:26:38 INFO - PROCESS | 1678 | RSS: Main: 246550528 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | __start_tp_report 05:26:38 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 05:26:38 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 05:26:38 INFO - PROCESS | 1678 | |i|pagename|runs| 05:26:38 INFO - PROCESS | 1678 | |0;ai-astar;117;113;166;164;117;129;115;117;115;115 05:26:38 INFO - PROCESS | 1678 | |1;audio-beat-detection;147;122;152;147;118;137;154;108;194;151 05:26:38 INFO - PROCESS | 1678 | |2;audio-dft;196;221;275;210;198;199;195;195;199;195 05:26:38 INFO - PROCESS | 1678 | |3;audio-fft;72;74;72;109;58;55;60;60;125;57 05:26:38 INFO - PROCESS | 1678 | |4;audio-oscillator;87;78;126;77;81;80;122;80;80;119 05:26:38 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;136;129;89;88;90;85;90;87;88;87 05:26:38 INFO - PROCESS | 1678 | |6;imaging-darkroom;103;140;95;101;95;95;94;94;93;96 05:26:38 INFO - PROCESS | 1678 | |7;imaging-desaturate;144;143;92;93;92;145;140;91;88;91 05:26:38 INFO - PROCESS | 1678 | |8;json-parse-financial;56;61;60;62;61;79;91;99;88;79 05:26:38 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;65;66;68;65;79;72;68;64;83;98 05:26:38 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;83;101;78;83;84;85;82;84;81;87 05:26:38 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;131;121;132;133;128;109;132;126;135;134 05:26:38 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;125;136;139;135;138;124;136;134;134;138 05:26:38 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;66;66;69;67;66;66;64;67;70;65 05:26:38 INFO - PROCESS | 1678 | __end_tp_report 05:26:38 INFO - PROCESS | 1678 | __start_cc_report 05:26:38 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,934 05:26:38 INFO - PROCESS | 1678 | __end_cc_report 05:26:38 INFO - PROCESS | 1678 | __startTimestamp1460031998189__endTimestamp 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | ------- Summary: start ------- 05:26:38 INFO - PROCESS | 1678 | Number of tests: 14 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:126.80 Median:117.00 stddev:20.61 (17.6%) stddev-sans-first:21.55 05:26:38 INFO - PROCESS | 1678 | Values: 117.0 113.0 166.0 164.0 117.0 129.0 115.0 117.0 115.0 115.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:143.00 Median:149.00 stddev:24.10 (16.2%) stddev-sans-first:25.52 05:26:38 INFO - PROCESS | 1678 | Values: 147.0 122.0 152.0 147.0 118.0 137.0 154.0 108.0 194.0 151.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:208.30 Median:199.00 stddev:24.89 (12.5%) stddev-sans-first:26.00 05:26:38 INFO - PROCESS | 1678 | Values: 196.0 221.0 275.0 210.0 198.0 199.0 195.0 195.0 199.0 195.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:74.20 Median:60.00 stddev:23.88 (39.8%) stddev-sans-first:25.31 05:26:38 INFO - PROCESS | 1678 | Values: 72.0 74.0 72.0 109.0 58.0 55.0 60.0 60.0 125.0 57.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:80.00 stddev:20.48 (25.6%) stddev-sans-first:21.60 05:26:38 INFO - PROCESS | 1678 | Values: 87.0 78.0 126.0 77.0 81.0 80.0 122.0 80.0 80.0 119.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:88.00 stddev:18.89 (21.5%) stddev-sans-first:13.76 05:26:38 INFO - PROCESS | 1678 | Values: 136.0 129.0 89.0 88.0 90.0 85.0 90.0 87.0 88.0 87.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:94.50 stddev:14.21 (15.0%) stddev-sans-first:15.05 05:26:38 INFO - PROCESS | 1678 | Values: 103.0 140.0 95.0 101.0 95.0 95.0 94.0 94.0 93.0 96.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:111.90 Median:91.00 stddev:26.83 (29.5%) stddev-sans-first:25.82 05:26:38 INFO - PROCESS | 1678 | Values: 144.0 143.0 92.0 93.0 92.0 145.0 140.0 91.0 88.0 91.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:73.60 Median:79.00 stddev:15.49 (19.6%) stddev-sans-first:15.07 05:26:38 INFO - PROCESS | 1678 | Values: 56.0 61.0 60.0 62.0 61.0 79.0 91.0 99.0 88.0 79.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:72.80 Median:70.00 stddev:10.90 (15.6%) stddev-sans-first:11.19 05:26:38 INFO - PROCESS | 1678 | Values: 65.0 66.0 68.0 65.0 79.0 72.0 68.0 64.0 83.0 98.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:83.50 stddev:6.18 (7.4%) stddev-sans-first:6.52 05:26:38 INFO - PROCESS | 1678 | Values: 83.0 101.0 78.0 83.0 84.0 85.0 82.0 84.0 81.0 87.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:128.10 Median:132.00 stddev:7.92 (6.0%) stddev-sans-first:8.33 05:26:38 INFO - PROCESS | 1678 | Values: 131.0 121.0 132.0 133.0 128.0 109.0 132.0 126.0 135.0 134.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:133.90 Median:136.00 stddev:5.24 (3.9%) stddev-sans-first:4.46 05:26:38 INFO - PROCESS | 1678 | Values: 125.0 136.0 139.0 135.0 138.0 124.0 136.0 134.0 134.0 138.0 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:1.78 (2.7%) stddev-sans-first:1.87 05:26:38 INFO - PROCESS | 1678 | Values: 66.0 66.0 69.0 67.0 66.0 66.0 64.0 67.0 70.0 65.0 05:26:38 INFO - PROCESS | 1678 | -------- Summary: end -------- 05:26:38 INFO - PROCESS | 1678 | 05:26:38 INFO - TEST-INFO | 1678: exit 0 05:26:38 INFO - TEST-OK | kraken | took 57703ms 05:26:38 INFO - SUITE-END | took 1168s 05:26:39 INFO - Completed test suite (00:19:29) 05:26:39 INFO - javascript benchmark 05:26:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3161.8735250589975, 3276.7542434317425, 3588.7308451866115, 3645.4896314515954, 3651.3245049665816, 5914.822555323336, 5916.083007133896, 5917.9408205917925, 5927.436893495111, 5948.167256584076, 5954.225950626413, 5983.790567330134, 6010.2186715727, 6018.39816018398, 6019.7893073742425, 5297.390800058004, 5302.284191634129, 5325.4142044375085, 5368.946310536884, 5418.7019713915715, 5282.577393808505, 5422.349318081816, 5435.483629055253, 5477.577198573424, 5517.779288828442, 5344.866378340534, 5351.464853514656, 5354.973225133869, 5356.089464790975, 5375.865603359908, 4810.254410566367, 5251.632385733, 5360.65155764874, 5417.83746487606, 5429.782808687648, 5267.0, 5342.439043900374, 5357.919631205535, 5363.249145119687, 5401.459854014606, 5928.6739229342365, 5975.701214939236, 5979.983402821531, 5999.910001349983, 6033.668148251844, 6107.583301036475, 6185.0, 6220.129181914535, 6230.906536401958, 6260.092286618415, 6278.591891527036, 6289.905651415233, 6342.87314253717, 6345.333739957284, 6359.968200159016, 5919.733611987471, 6080.361562036011, 6081.847953801146, 6083.7566497340495, 6108.267007959029, 6353.4599559038, 6417.26201486835, 6431.614103153732, 6442.871142577123, 6448.226212854487, 5543.805966791224, 5899.852503687356, 6204.852102360987, 6212.4408803207025, 6215.160953271251, 6159.384061593894, 6208.4722798561725, 6289.905651415233, 6292.8426789329715, 6295.0, 6163.84590385244, 6224.097505861715, 6227.221597300338, 6233.937660623426, 6253.812385628438, 6170.50635949123, 6228.750849966042, 6233.3143354231, 6238.968805156036, 6250.124982502362, 4897.094037602984, 4974.8756281093265, 5008.69947803133, 5027.874303142377, 5040.747962601855, 4494.70784399013, 4495.190865644216, 4497.572730590588, 4516.458025037016, 4528.252838281713, 5812.808374283261, 6025.0359942409, 6042.154098426224, 6062.545309101835, 6075.422834830684, 5154.433012368637, 6265.96867015662, 6305.4009869062365, 6311.495080393557, 6332.1135041094285, 5124.897502050013, 5844.88310233793, 6030.6080104793045, 6041.909371359433, 6115.052166914135, 5500.944990550043, 5540.058190107691, 5546.140348246028, 5579.804706835242, 5580.693061881636, 6340.556161068773, 6463.0, 6529.9020514691365, 6584.374484423972, 6585.374389432996, 5465.797524544594, 6294.842628934313, 6305.464035557029, 6310.6213627182515, 6315.210598675166, 6162.235583165155, 6528.706208220737, 6541.901871471837, 6547.410733034049, 6551.4103730664465, 5230.241614965733, 6171.352008039271, 6174.413430724074, 6202.038684003986, 6250.0, 6310.05349197624, 6331.620102793847, 6338.239411270677, 6340.175777148941, 6381.8085457436355, 5683.687397193194, 5889.764409423747, 6209.037599171955, 6244.0, 6271.216097987752, 6093.238345206849, 6128.258691089361, 6147.047207682816, 6151.477124444473, 6158.907616385668, 5472.015037293326, 5616.494515493625, 6084.11780291864, 6181.81454556364, 6224.782132625336, 6140.293866205444, 6168.506519478341, 6209.565330426827, 6230.563860529719, 6243.37566243372, 5299.284596579567, 6069.575129740876, 6206.72069756853, 6222.719977600928, 6235.75056997715], "unit": "score", "name": "dojo.html", "value": 5795.672619137206}, {"lowerIsBetter": false, "replicates": [459.27663929311325, 515.6983164848564, 617.2716194890028, 643.3662842100531, 665.2515919590459, 29134.708652913465, 36502.627329223615, 37832.81083594581, 38480.03799905004, 38793.41809872854, 39458.81623551291, 39643.00892477689, 39931.201375972465, 39969.60030399695, 40059.0, 9351.76620584484, 9546.427214367126, 9559.904400956006, 9564.234861211087, 9594.76013099671, 7911.2088791121005, 8090.959545202266, 9617.326787124905, 9627.807443851154, 9662.951685241564, 9556.80886382272, 9633.084856938614, 9648.131668149901, 9650.71047868565, 9663.0, 9559.522023898777, 9564.760880977961, 9598.040195980417, 9618.374805637646, 9656.08267214617, 8483.957580212122, 9519.0, 9536.046395360478, 9678.370905891095, 9697.612095516171, 33419.49870751941, 39343.803280983695, 39375.21249575021, 39599.0, 39816.80549583517, 3034.5599888016336, 3196.9130495631366, 3215.4212241796467, 3265.96734032659, 3395.1172695099203, 22325.44186395337, 26675.46649067027, 26895.731042689713, 26911.192664220103, 27375.04187353433, 2245.854019488744, 2296.2651951375565, 2316.7219933607907, 2778.1665500349814, 2867.426514697069, 238217.0, 285498.0, 298275.5086224555, 310133.4493327519, 321525.0, 47120.05759884783, 47130.0, 47462.288065679044, 56457.717711412, 64105.71788564203, 46085.539144608796, 46097.0, 46127.30809037867, 46302.53697463049, 46403.83990400267, 16627.418040368528, 16789.496315110566, 16801.66396672084, 16808.991460512163, 16820.327186912633, 13195.20828750278, 13274.800877986838, 13351.0, 13420.993425493127, 13511.594652160451, 15290.0, 15323.846761532464, 15371.385144594315, 15404.0, 15405.306761195772, 6438.806835794933, 6586.110875031809, 6592.901106483407, 6616.338366163345, 6625.370589794058, 1009.2178561614808, 1029.6447725534679, 1038.3406536849154, 1043.4521876015153, 1047.1099565369375, 30136.24659383489, 30330.0, 30388.84805575958, 30761.69238307633, 30800.0, 1026.947378936596, 1160.2400427719856, 1192.65413030222, 1238.6407941696807, 1247.8253044573767, 14711.411543538352, 15298.617534561725, 15408.614784630474, 15690.764638530203, 15742.685146297239, 502796.0, 719403.0, 736742.0, 738236.0, 739241.0, 59953.70023149944, 60267.095993560135, 60460.395396046355, 60968.695156524824, 61048.38951610427, 1513.9091654500764, 1514.9318280677176, 1518.2788175616715, 1521.4598817419878, 1525.0544662309217, 28795.992140275826, 29623.703762962094, 29971.55042674318, 30270.789168433897, 30576.0, 51186.0, 51194.23208651948, 51253.48746512487, 51600.74199628978, 51622.48377516327, 459464.0, 473403.0, 473933.0, 474006.0, 475403.0, 32831.0, 33232.33535329328, 33236.1690957728, 33430.49854252237, 33613.15967100842, 44332.556674434134, 47701.0, 48284.27573586469, 48942.02115957732, 49215.76960576014, 37637.0, 38428.0, 38429.423558646085, 38532.61467385403, 38586.42120368253, 37297.81351093227, 37411.0, 37653.7519946877, 38021.61978380291, 38095.0, 33174.83412582921, 37151.0, 38835.83492495117, 40037.39943900902, 40587.797061014506, 30937.69062309348, 32385.35229295448, 33782.0, 33939.830300848334, 34111.14722131967, 25082.74917250804, 25335.87332063328, 25491.10781122726, 25707.485850283265, 25804.483910322066], "unit": "score", "name": "ext.html", "value": 19176.6900647712}, {"lowerIsBetter": false, "replicates": [368.6184798733312, 370.82818294190366, 379.67537755219286, 385.7126440801603, 390.5313623651615, 16652.83347166528, 20222.494437639067, 20472.385828425133, 20973.265935692234, 21032.579348413023, 17959.0, 21416.8929155354, 21450.24924127652, 21666.35000949974, 21732.34802955914, 5604.1593760935775, 5923.526117910578, 5931.762729490837, 5932.0, 5992.310884248302, 5508.91736623949, 5822.097574875901, 5828.154917536955, 5838.036723940545, 5839.649621022731, 5655.915161272585, 5731.484166425021, 5743.741531631065, 5764.308283006045, 5777.62445441045, 5264.552513036378, 5736.196932429484, 5753.367129615739, 5753.856153596152, 5755.39568345324, 4940.209566469383, 4991.825286115005, 5039.193729003378, 5048.0, 5056.595472362203, 25948.87025564879, 25988.09041683551, 26308.21075367742, 26459.073932412273, 26742.0, 2339.4034521196986, 2356.2342238772294, 2415.987920060406, 2635.683717953839, 2920.8977685780897, 9504.002079781661, 10049.045340692623, 14756.631084222872, 14853.108813471119, 15181.6204594886, 1704.326790917593, 1774.157275294225, 1776.111944027986, 1810.7283907413862, 1818.5999080202466, 87120.49182367996, 90152.54923725405, 93149.60275595804, 93176.53411732966, 94234.0, 11423.428828558539, 11465.369404682822, 11490.195686301879, 11658.06735461161, 11716.297022178696, 14030.947678924122, 14138.50515231962, 14149.646258843406, 14198.85801141996, 14269.64325891861, 7505.587192704454, 8872.911270887338, 8979.416337938144, 9031.729048128567, 9118.772030699285, 9198.540072996457, 9259.259259259243, 9275.61661316374, 9284.535773211312, 9369.063093690713, 4457.68796184264, 4523.344115103291, 4551.5676010778925, 4562.790860421991, 4576.656750743708, 1895.4503194073563, 1951.365806113019, 1953.687410014391, 1978.851586131046, 1981.603679264153, 828.362161135922, 907.8865141857268, 995.3629677006721, 1029.1818004686293, 1043.3270540501485, 19580.0, 20608.089539560737, 20622.896885515478, 20665.79334206669, 20958.47603809917, 1003.087190656499, 1026.1790567545934, 1051.323139592349, 1074.6238816414193, 1081.302559848893, 10548.683539493828, 11091.889081109248, 11112.944435277934, 11126.554937802559, 11157.72105697364, 480799.0, 490477.0, 491466.0, 580424.0, 581543.0, 100432.0, 104620.47689761502, 106518.0, 112033.0, 125347.0, 1046.1735229168871, 1089.7874914391618, 1097.4567589043475, 1097.665212110314, 1098.6868742408535, 13735.793963090762, 17579.472615821545, 17913.283468661106, 17963.730544041588, 18302.81697183011, 51568.74215628898, 52688.73655631697, 52738.47261527336, 52872.206916895506, 53860.0, 79322.6033869827, 80014.19985800068, 80114.0, 81309.78035329594, 81505.59247203726, 7925.881111783443, 8072.959635201786, 8151.592420378839, 8174.67301307941, 8442.873356899774, 9425.340226184102, 12647.304398258184, 13041.673958151123, 13424.597262082154, 13620.659483512594, 11225.943870280924, 11368.94315528417, 11539.07687385024, 11639.418029098342, 11859.940700296444, 11442.141839361915, 11563.190576659554, 11730.178887478136, 11801.645950621494, 11851.644450666494, 11905.0, 11987.22083064598, 12064.819027714415, 12067.336296503425, 12208.206466579644, 8204.507729536246, 8311.96100487439, 8319.708810191614, 8345.33237340999, 8383.62273697673, 8545.74362769118, 11194.216404851582, 11955.462004209658, 11972.0, 12011.159218854596], "unit": "score", "name": "jquery.html", "value": 9591.013893473728}, {"lowerIsBetter": false, "replicates": [1378.8000739892714, 1419.666378401076, 1447.5729659750373, 1601.5755824706455, 1691.196681576252, 8341.749747507569, 8985.730428087152, 9095.408798428098, 9247.90752092479, 9276.11876871697, 8048.758537243877, 8762.992255890575, 9021.503817290046, 9137.725868223948, 9155.450672959627, 2540.2760213339157, 2562.1544890186287, 2566.7304932982047, 2574.3306740247485, 2577.6004719268544, 2516.7105782834933, 2586.314626623937, 2595.7144714081437, 2597.935051623715, 2606.439615482671, 2546.1088618983395, 2574.2792018234923, 2606.5177942080777, 2612.4644447888136, 2614.9670880002386, 2188.4638263625357, 2214.9921785587517, 2554.0, 2590.676165479315, 2604.0625374865035, 2567.0245306783304, 2573.974260257392, 2600.388908606476, 2604.2577865308485, 2609.686837579484, 9110.36227464071, 9199.080091990814, 9211.032841551674, 9212.815743685089, 9281.97898231194, 5630.915536266959, 5703.828885133453, 5745.885082298372, 5802.0, 5820.650760954314, 3609.3322735294055, 3696.6488183622514, 3701.148735790783, 3756.9624303756887, 3791.203847192093, 1048.26621365044, 1067.1036329483272, 1070.1409781446596, 1075.4353964168795, 1108.545496346494, 10718.196135289885, 12348.876511234952, 12407.069469789802, 12449.377531123499, 12471.939885109687, 5055.545000949932, 5085.516875896784, 5100.566451851634, 5153.974230128825, 5173.456787037382, 10656.147508199325, 10698.03717665414, 10700.946495267473, 10716.571337146426, 10740.087092597218, 5735.311762588516, 5844.473997340175, 5844.824655260349, 5857.648541087463, 5860.589758716934, 4169.332906734908, 4657.74382408964, 5142.794288228504, 5159.071367153948, 5217.973910130501, 4121.052158003676, 4126.429320417413, 4170.457840480718, 4177.665786737054, 4197.706160568792, 1351.3918736568387, 1380.6548362909273, 1380.8757211850982, 1384.439302082638, 1390.7705228637365, 1397.0988712280523, 1412.0750908155176, 1429.3639330497829, 1440.942362305517, 1476.018447732246, 7538.698452061856, 8272.503649780912, 8495.277901378453, 8551.102134275936, 8632.093630168867, 522.2010324203974, 523.5314393617767, 532.2176400690978, 532.3452153850785, 532.5473347654463, 6363.045543168561, 6370.713317900706, 6415.037744338387, 6428.935710642927, 6449.516286278548, 10960.451977401099, 11397.772044559062, 11436.542538298374, 11445.313281203154, 11477.0, 10856.511456984455, 11092.556297748, 11158.330500170016, 11214.775704485864, 11221.0, 8700.52147131901, 8743.125687431333, 8804.163604457566, 8840.292776577859, 8853.424527405698, 6934.549254298455, 7020.859582808213, 8105.0, 8204.671813127408, 8316.501009939422, 8367.95816020916, 9720.951395242979, 9896.010398960047, 10073.445960472245, 10206.387616743019, 10217.284790064921, 10546.26176167661, 10547.314424562379, 10582.470876455993, 10636.30863993838, 7800.219978002381, 7848.725294614661, 7866.410019248694, 7933.0, 7952.681892724227, 8507.95746021266, 8591.613377397907, 8633.481991080555, 8646.913530864862, 8684.609192586222, 6550.672466376757, 7543.35881450061, 7650.464467487445, 7713.336752630372, 7837.451378403457, 7511.436642251962, 7629.351505122127, 7649.120351159465, 7745.419093567893, 7775.028121484815, 8116.391270654606, 8127.1060183378595, 8175.754727358189, 8197.89328440653, 8298.58507074656, 6254.718537665725, 7288.453365997465, 7412.703491860265, 7420.072490938633, 7444.925550744423, 7523.360514356341, 7544.811379715551, 7556.677581423339, 7567.508111972704, 7585.734499292498], "unit": "score", "name": "mootools.html", "value": 5026.130011324509}, {"lowerIsBetter": false, "replicates": [330.51084395095256, 332.6523782647134, 332.7388000419671, 337.84625503904164, 339.2858033838768, 6924.930750692498, 6929.896051559218, 6942.548734332271, 6951.791446256608, 6959.373656370925, 6781.728730850772, 6844.931550684491, 6899.3445622665895, 6915.170179578445, 6918.135233095863, 4864.516322521629, 4894.804207831682, 4980.626453016038, 5026.52248036436, 5071.644984851071, 4057.3102572562734, 4963.677360971544, 4970.378702662168, 4986.626003049768, 5021.9748901255625, 4922.310876477296, 4933.939203071339, 4938.580220681247, 4941.555260026596, 4964.106460837048, 4937.456879743226, 4953.108440480712, 4961.107000739866, 4962.354893863775, 4966.255061740731, 4271.145770845843, 4289.892752681177, 4291.420658211133, 4297.119090586422, 4318.805653745589, 6342.714577844008, 6616.702248398834, 6633.9668301658185, 6642.800715978528, 6690.531662783607, 5871.14868344092, 5913.733881975321, 5916.437938395846, 5926.170336152942, 5927.673977931212, 7384.889226661604, 7414.073240844895, 7420.146683131401, 7424.777256682308, 7441.479096463249, 4688.859334219979, 4691.671582989192, 4696.319033740123, 4698.718076915396, 4710.0, 5131.769070391804, 7768.028996375453, 7849.607519623996, 7869.645865936047, 7871.094824095245, 4570.497245303014, 4630.004549021909, 4687.882802929954, 4704.223803072455, 4758.191107511731, 4839.806407743651, 4840.491748366441, 4842.201036828954, 4846.39420072491, 4859.514048595112, 5822.417758224144, 5867.061270196833, 5889.204957330791, 5892.823215303548, 5896.321922978912, 7408.888866667005, 7418.666160022812, 7438.070241219848, 7438.5908775016815, 7466.775996720107, 1657.883948123644, 1685.9662806743797, 1686.5783554111472, 1689.9662006759795, 1691.3319238900565, 1012.8251228574965, 1056.4944953441316, 1065.9200559958035, 1067.4929408530886, 1067.9839802402971, 6773.221079575814, 6832.145981752281, 6875.828104297432, 6886.586804791728, 6909.619970901548, 7539.698412063456, 7631.3513351365755, 7636.656350464243, 7639.350655194259, 7656.310932016034, 6819.318068193141, 6830.214525329551, 6849.280825513349, 6857.108575885203, 6862.588244705234, 4023.939640905389, 4047.8583249586122, 4067.247559201557, 4076.062505623723, 4079.918401631951, 7350.70597176107, 7834.960825195838, 8146.289279251424, 8151.347892168612, 8151.429399941947, 8000.0, 8005.479643823133, 8009.559474228973, 8020.959895200486, 8056.194380562014, 7305.305995930378, 8105.230003149692, 8108.797280067927, 8133.877991830127, 8155.143709910374, 7544.585047822423, 7703.922960770321, 7783.455158138876, 7793.532388056735, 7823.100343460575, 7582.165961744094, 7591.4685971981435, 7625.580593067212, 7633.618319084135, 7653.617319133909, 6591.406773390418, 8133.055468097849, 8148.877766833383, 8153.7553873383895, 8161.836763264821, 4778.59381952524, 4821.975890120527, 4828.975855120702, 4844.927326090182, 4852.757362131809, 7376.299251571092, 7505.549667019996, 7655.0, 7682.154962954182, 7768.689252429839, 7473.252674732484, 7499.325060744341, 7505.924940750741, 7516.586587737508, 7541.622918854145, 6117.755289788359, 7431.628418578941, 7460.95546623484, 7480.027596412431, 7504.437167212372, 5952.642841429528, 6619.933800661932, 6699.866002680016, 6985.790426287016, 7328.926710732824, 6085.878282434415, 7420.888686669597, 7497.250274972459, 7499.100107987077, 7500.662470188746, 7308.415326773952, 7328.96335518319, 7347.448941329529, 7354.411647068115, 7373.078365204349], "unit": "score", "name": "prototype.html", "value": 5349.804461429277}, {"lowerIsBetter": false, "replicates": [670.9127813384259, 695.4401706626167, 715.2311265389706, 746.1046743907312, 750.5046669198326, 14995.875309351815, 15116.470923517665, 15209.54371368858, 15292.006019608718, 15358.616034599141, 14328.140311581312, 14448.422063117489, 14491.34788934498, 14501.637459063528, 14523.927380363084, 802.297989259398, 896.5786080542151, 983.7589790501302, 995.5719040812447, 1010.7271036820055, 993.0913214408807, 997.4962643864874, 1000.3997601439115, 1003.1874181912674, 1004.1364426593124, 1021.075926286708, 1046.481991414251, 1048.8951104889488, 1051.148569658575, 1052.2318707343643, 920.8581359114717, 1000.5447521377829, 1021.6628512590864, 1034.4414016431117, 1040.8907064758166, 746.9663865126082, 765.0169532151215, 772.5557804262571, 776.7048521561827, 780.8204113053972, 15183.696326073417, 15253.847461525465, 15463.845361546466, 15547.455839045579, 15563.533094007198, 13159.671008224776, 13206.075574709774, 13217.801732974012, 13225.86774132256, 13311.733765324736, 10207.693769186862, 10270.589176432934, 10307.381557106597, 10390.168786497063, 10432.061114499731, 3498.3702933471964, 3516.4197907345515, 3549.680528752399, 3613.5483064616924, 3782.111203867089, 65883.352916177, 69073.61852762967, 79015.0, 79623.0, 79962.60018699928, 9657.90342096584, 9663.985281545478, 9690.903090969001, 9798.853017204749, 9935.552900119512, 22199.77800221977, 22376.664350034764, 22397.10411583551, 22399.552008960054, 22425.66361504579, 7321.853562928818, 7401.851962960711, 7416.332530072319, 7437.51656142349, 7491.775246742606, 4084.81618327176, 4135.255653982312, 4172.979135104305, 4240.194363071007, 4308.138372325548, 3712.4988126603103, 3729.7202709796875, 3762.3792074307435, 3766.37854753968, 3768.962310376916, 652.9934700653027, 656.8029591122644, 659.561391674531, 668.3717305732692, 686.993130068693, 499.68270148455264, 503.54177698294376, 504.2738456622452, 614.1034090228228, 623.2271982741368, 7116.964415177891, 7723.729669461654, 7865.938098356649, 8192.385571082194, 8380.958095209608, 523.1472699499791, 603.405645439242, 609.7896225802091, 612.2897438970773, 614.4163045107166, 3758.285925674113, 3792.27946690128, 3823.6941044716355, 3848.826802793825, 4025.577314382006, 90461.0, 90461.54769226111, 90805.0, 95837.0, 96217.0, 77887.0, 78641.82037269445, 79550.0, 82184.58907705423, 86254.0, 63296.367036329044, 64009.679951599945, 64145.35854641394, 64717.70564588682, 68952.0, 7705.653245603849, 7817.413693972861, 7866.370690344873, 7875.763727088197, 8077.571837789793, 50766.7308317295, 50959.980800384525, 51138.488615113376, 52106.218406724685, 53175.46824531705, 66124.6693766528, 69161.0, 71716.64141679258, 74162.62918685372, 75322.87015694659, 10207.183425325808, 10343.534540945526, 10765.892341076802, 10951.313101111215, 11167.609133680282, 12049.33728644933, 12057.15599907998, 12078.577249796215, 12080.0, 12592.118551701293, 11330.546778128783, 11397.772044558897, 11449.942750286194, 11450.370229637118, 12181.939090304491, 11216.495257713586, 11254.493547790533, 11259.211855170059, 11538.423078846192, 12161.087918406332, 11052.502637381176, 11112.222144450134, 11280.49237784318, 11341.432928353384, 11403.087752979907, 9360.15758581731, 9445.858312125461, 9671.613135474503, 9697.806043879224, 9700.0, 8534.658613655385, 8746.300295976434, 8754.430961987451, 9216.539173041456, 9632.566534505824], "unit": "score", "name": "yui.html", "value": 6999.5280325502545}], "name": "dromaeo_css", "value": 7651.308628651785}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [117.0, 113.0, 166.0, 164.0, 117.0, 129.0, 115.0, 117.0, 115.0, 115.0], "unit": "score", "name": "ai-astar", "value": 126.8}, {"lowerIsBetter": true, "replicates": [147.0, 122.0, 152.0, 147.0, 118.0, 137.0, 154.0, 108.0, 194.0, 151.0], "unit": "score", "name": "audio-beat-detection", "value": 143.0}, {"lowerIsBetter": true, "replicates": [196.0, 221.0, 275.0, 210.0, 198.0, 199.0, 195.0, 195.0, 199.0, 195.0], "unit": "score", "name": "audio-dft", "value": 208.3}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 72.0, 109.0, 58.0, 55.0, 60.0, 60.0, 125.0, 57.0], "unit": "score", "name": "audio-fft", "value": 74.2}, {"lowerIsBetter": true, "replicates": [87.0, 78.0, 126.0, 77.0, 81.0, 80.0, 122.0, 80.0, 80.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 93.0}, {"lowerIsBetter": true, "replicates": [136.0, 129.0, 89.0, 88.0, 90.0, 85.0, 90.0, 87.0, 88.0, 87.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 96.9}, {"lowerIsBetter": true, "replicates": [103.0, 140.0, 95.0, 101.0, 95.0, 95.0, 94.0, 94.0, 93.0, 96.0], "unit": "score", "name": "imaging-darkroom", "value": 100.6}, {"lowerIsBetter": true, "replicates": [144.0, 143.0, 92.0, 93.0, 92.0, 145.0, 140.0, 91.0, 88.0, 91.0], "unit": "score", "name": "imaging-desaturate", "value": 111.9}, {"lowerIsBetter": true, "replicates": [56.0, 61.0, 60.0, 62.0, 61.0, 79.0, 91.0, 99.0, 88.0, 79.0], "unit": "score", "name": "json-parse-financial", "value": 73.6}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 68.0, 65.0, 79.0, 72.0, 68.0, 64.0, 83.0, 98.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 72.8}, {"lowerIsBetter": true, "replicates": [83.0, 101.0, 78.0, 83.0, 84.0, 85.0, 82.0, 84.0, 81.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.8}, {"lowerIsBetter": true, "replicates": [131.0, 121.0, 132.0, 133.0, 128.0, 109.0, 132.0, 126.0, 135.0, 134.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 128.1}, {"lowerIsBetter": true, "replicates": [125.0, 136.0, 139.0, 135.0, 138.0, 124.0, 136.0, 134.0, 134.0, 138.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 133.9}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 69.0, 67.0, 66.0, 66.0, 64.0, 67.0, 70.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.6}], "name": "kraken", "value": 1514.4999999999998}]} 05:26:39 INFO - Return code: 0 05:26:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:26:39 ERROR - # TBPL SUCCESS # 05:26:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:39 INFO - 2016-04-07 05:26:39.502 screenresolution[1683:10296] starting screenresolution argv=screenresolution get 05:26:39 INFO - 2016-04-07 05:26:39.505 screenresolution[1683:10296] Display 0: 1600x1200x32@60 05:26:39 INFO - 2016-04-07 05:26:39.511 screenresolution[1684:10299] starting screenresolution argv=screenresolution list 05:26:39 INFO - Available Modes on Display 0 05:26:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:39 INFO - Intel Iris: 05:26:39 INFO - Chipset Model: Intel Iris 05:26:39 INFO - Type: GPU 05:26:39 INFO - Bus: Built-In 05:26:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:39 INFO - Vendor: Intel (0x8086) 05:26:39 INFO - Device ID: 0x0a2e 05:26:39 INFO - Revision ID: 0x0009 05:26:39 INFO - Displays: 05:26:39 INFO - Display: 05:26:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:39 INFO - Display Serial Number: 1600x1200 60 05:26:39 INFO - Main Display: Yes 05:26:39 INFO - Mirror: Off 05:26:39 INFO - Online: Yes 05:26:39 INFO - Rotation: Supported 05:26:39 INFO - Return code: 0 05:26:39 INFO - Running post-action listener: _resource_record_post_action 05:26:39 INFO - Running post-run listener: _resource_record_post_run 05:26:40 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 37434880; Write bytes: 666887168; Read time: 395; Write time: 1958 05:26:40 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 356551168; Write bytes: 353552384; Read time: 27927; Write time: 662 05:26:40 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 35452416; Write bytes: 302160896; Read time: 369; Write time: 1261 05:26:40 INFO - Running post-run listener: _upload_blobber_files 05:26:40 INFO - Blob upload gear active. 05:26:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:40 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:26:40 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:26:40 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:26:41 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:26:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:26:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:42 INFO - (blobuploader) - INFO - Done attempting. 05:26:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:26:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:26:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:43 INFO - (blobuploader) - INFO - Done attempting. 05:26:43 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:43 INFO - Return code: 0 05:26:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/300f936b9c93c5f901df77879825b78ffa65b8a67e5145f7cd938c6f5015365aef95f03cfbcee67da652ff08b79460bf34e4fda3a9fb2826296c4c0ccd2843e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5928b26a0d1928cd0cc46bd85e480c5bb28359929d675f63241e8d193e99acc44eb06bb161d7aef08712fc9aa3707a087cc80bad7476422122faf2073ac3aa24"} 05:26:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:43 INFO - Contents: 05:26:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/300f936b9c93c5f901df77879825b78ffa65b8a67e5145f7cd938c6f5015365aef95f03cfbcee67da652ff08b79460bf34e4fda3a9fb2826296c4c0ccd2843e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5928b26a0d1928cd0cc46bd85e480c5bb28359929d675f63241e8d193e99acc44eb06bb161d7aef08712fc9aa3707a087cc80bad7476422122faf2073ac3aa24"} 05:26:43 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:43 INFO - Copying logs to upload dir... 05:26:43 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.671453 ========= master_lag: 3.77 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-04-07 05:26:47.570937) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:47.574265) ========= 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.XtoGk8U16N/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.zQV3ES60hH/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/300f936b9c93c5f901df77879825b78ffa65b8a67e5145f7cd938c6f5015365aef95f03cfbcee67da652ff08b79460bf34e4fda3a9fb2826296c4c0ccd2843e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5928b26a0d1928cd0cc46bd85e480c5bb28359929d675f63241e8d193e99acc44eb06bb161d7aef08712fc9aa3707a087cc80bad7476422122faf2073ac3aa24"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011118 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/300f936b9c93c5f901df77879825b78ffa65b8a67e5145f7cd938c6f5015365aef95f03cfbcee67da652ff08b79460bf34e4fda3a9fb2826296c4c0ccd2843e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5928b26a0d1928cd0cc46bd85e480c5bb28359929d675f63241e8d193e99acc44eb06bb161d7aef08712fc9aa3707a087cc80bad7476422122faf2073ac3aa24"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:47.612796) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:47.613294) ========= 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.XtoGk8U16N/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.zQV3ES60hH/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005269 ========= master_lag: 0.64 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:48.255540) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:26:48.255925) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:26:48.256719) ========= ========= Total master_lag: 9.93 =========