builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0047 starttime: 1460030739.5 results: success (0) buildid: 20160407044320 builduid: 6361d95345d74de79eea49bc7019986f revision: 2355485d9a8e09c7cfe47063bd192b72ec34d286 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.496412) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.496812) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.497111) ========= 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.UejQCuNJec/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.d28Gtql7fj/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.005876 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.519468) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.519740) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.535865) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.536212) ========= 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.UejQCuNJec/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.d28Gtql7fj/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.005236 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.585086) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.585387) ========= 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:39.585750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.586042) ========= 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.UejQCuNJec/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.d28Gtql7fj/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.29M=0.002s 2016-04-07 05:05:39 (7.29 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090750 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.692134) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.692447) ========= 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.UejQCuNJec/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.d28Gtql7fj/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.019747 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:39.741142) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 05:05:39.741487) ========= 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.UejQCuNJec/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.d28Gtql7fj/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:39,817 truncating revision to first 12 chars 2016-04-07 05:05:39,817 Setting DEBUG logging. 2016-04-07 05:05:39,817 attempt 1/10 2016-04-07 05:05:39,817 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:40,854 unpacking tar archive at: mozilla-aurora-2355485d9a8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.295012 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 05:05:41.053847) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:41.054215) ========= 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.UejQCuNJec/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.d28Gtql7fj/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.005625 script_repo_revision: '2355485d9a8e09c7cfe47063bd192b72ec34d286' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:41.105045) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:41.105355) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:41.120476) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-04-07 05:05:41.121016) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.UejQCuNJec/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.d28Gtql7fj/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:41 INFO - MultiFileLogger online at 20160407 05:05:41 in /builds/slave/test 05:05:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:41 INFO - {'append_to_log': False, 05:05:41 INFO - 'base_work_dir': '/builds/slave/test', 05:05:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:05:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:41 INFO - 'branch': 'Mozilla-Aurora', 05:05:41 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:41 INFO - 'config_files': ('talos/mac_config.py',), 05:05:41 INFO - 'default_actions': ('clobber', 05:05:41 INFO - 'read-buildbot-config', 05:05:41 INFO - 'download-and-extract', 05:05:41 INFO - 'populate-webroot', 05:05:41 INFO - 'create-virtualenv', 05:05:41 INFO - 'install', 05:05:41 INFO - 'run-tests'), 05:05:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:41 INFO - 'download_minidump_stackwalk': True, 05:05:41 INFO - 'download_symbols': 'ondemand', 05:05:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:41 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:41 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:41 INFO - 'installer_path': 'installer.exe', 05:05:41 INFO - 'log_level': 'info', 05:05:41 INFO - 'log_name': 'talos', 05:05:41 INFO - 'log_to_console': True, 05:05:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:05:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:05:41 INFO - 'opt_config_files': (), 05:05:41 INFO - 'pip_index': False, 05:05:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:41 INFO - 'cmd': ('bash', 05:05:41 INFO - '-c', 05:05:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:41 INFO - 'enabled': True, 05:05:41 INFO - 'halt_on_failure': False, 05:05:41 INFO - 'name': 'check_screen_resolution'},), 05:05:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:41 INFO - 'cmd': ('bash', 05:05:41 INFO - '-c', 05:05:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:41 INFO - 'enabled': True, 05:05:41 INFO - 'halt_on_failure': False, 05:05:41 INFO - 'name': 'check_screen_resolution'},), 05:05:41 INFO - 'run_cmd_checks_enabled': True, 05:05:41 INFO - 'sps_profile': False, 05:05:41 INFO - 'sps_profile_interval': 0, 05:05:41 INFO - 'suite': 'dromaeojs', 05:05:41 INFO - 'system_bits': '32', 05:05:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:41 INFO - 'title': 't-yosemite-r7-0047', 05:05:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:41 INFO - 'use_talos_json': True, 05:05:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:41 INFO - 'work_dir': 'build'} 05:05:41 INFO - ##### 05:05:41 INFO - ##### Running clobber step. 05:05:41 INFO - ##### 05:05:41 INFO - Running pre-action listener: _resource_record_pre_action 05:05:41 INFO - Running main action method: clobber 05:05:41 INFO - rmtree: /builds/slave/test/build 05:05:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:43 INFO - Running post-action listener: _resource_record_post_action 05:05:43 INFO - ##### 05:05:43 INFO - ##### Running read-buildbot-config step. 05:05:43 INFO - ##### 05:05:43 INFO - Running pre-action listener: _resource_record_pre_action 05:05:43 INFO - Running main action method: read_buildbot_config 05:05:43 INFO - Using buildbot properties: 05:05:43 INFO - { 05:05:43 INFO - "project": "", 05:05:43 INFO - "product": "firefox", 05:05:43 INFO - "script_repo_revision": "production", 05:05:43 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:05:43 INFO - "repository": "", 05:05:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:05:43 INFO - "buildid": "20160407044320", 05:05:43 INFO - "stage_platform": "macosx64", 05:05:43 INFO - "basedir": "/builds/slave/test", 05:05:43 INFO - "buildnumber": 166, 05:05:43 INFO - "platform": "yosemite_r7", 05:05:43 INFO - "mozci_request": { 05:05:43 INFO - "priority": -1, 05:05:43 INFO - "type": "trigger_all_talos_jobs", 05:05:43 INFO - "times": 6 05:05:43 INFO - }, 05:05:43 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:05:43 INFO - "slavebuilddir": "test", 05:05:43 INFO - "branch": "mozilla-aurora", 05:05:43 INFO - "revision": "2355485d9a8e09c7cfe47063bd192b72ec34d286", 05:05:43 INFO - "slavename": "t-yosemite-r7-0047", 05:05:43 INFO - "builduid": "6361d95345d74de79eea49bc7019986f", 05:05:43 INFO - "repo_path": "releases/mozilla-aurora" 05:05:43 INFO - } 05:05:43 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:43 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:43 INFO - Running post-action listener: _resource_record_post_action 05:05:43 INFO - ##### 05:05:43 INFO - ##### Running download-and-extract step. 05:05:43 INFO - ##### 05:05:43 INFO - Running pre-action listener: _resource_record_pre_action 05:05:43 INFO - Running main action method: download_and_extract 05:05:43 INFO - mkdir: /builds/slave/test/build/tests 05:05:43 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:43 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:43 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:43 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:43 INFO - Downloaded 1277 bytes. 05:05:43 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 05:05:43 INFO - Using the following test package requirements: 05:05:43 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:43 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:43 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 05:05:43 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:43 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 05:05:43 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:43 INFO - u'jsshell-mac.zip'], 05:05:43 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:43 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 05:05:43 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:43 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:43 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 05:05:43 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:43 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 05:05:43 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:43 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 05:05:43 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:43 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:43 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 05:05:43 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:05:43 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:43 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:43 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:43 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:43 INFO - Downloaded 17904082 bytes. 05:05:43 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:43 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:46 INFO - Return code: 0 05:05:46 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:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:46 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:46 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:46 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:47 INFO - Downloaded 17904082 bytes. 05:05:47 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:47 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:50 INFO - Return code: 0 05:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:50 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip 05:05:50 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:05:50 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:05:50 INFO - Downloaded 10845168 bytes. 05:05:50 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:05:50 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:05:51 INFO - Return code: 0 05:05:51 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:51 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:05:51 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:05:51 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:05:52 INFO - Downloaded 115185382 bytes. 05:05:52 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:05:52 INFO - mkdir: /builds/slave/test/properties 05:05:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:52 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:52 INFO - Contents: 05:05:52 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:05:53 INFO - Running post-action listener: _resource_record_post_action 05:05:53 INFO - Running post-action listener: set_extra_try_arguments 05:05:53 INFO - ##### 05:05:53 INFO - ##### Running populate-webroot step. 05:05:53 INFO - ##### 05:05:53 INFO - Running pre-action listener: _resource_record_pre_action 05:05:53 INFO - Running main action method: populate_webroot 05:05:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:53 INFO - '--tppagecycles', 05:05:53 INFO - '7'], 05:05:53 INFO - 'tests': ['tsvgm']}}, 05:05:53 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:05:53 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:05:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:53 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:05:53 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:05:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:53 INFO - 'talos_options': ['--e10s'], 05:05:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:53 INFO - 'tests': ['damp', 'tps']}, 05:05:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:53 INFO - 'talos_options': ['--e10s'], 05:05:53 INFO - 'tests': ['damp', 'tps']}, 05:05:53 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:05:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:53 INFO - 'other': {'tests': ['a11yr', 05:05:53 INFO - 'ts_paint', 05:05:53 INFO - 'tpaint', 05:05:53 INFO - 'sessionrestore', 05:05:53 INFO - 'sessionrestore_no_auto_restore', 05:05:53 INFO - 'tabpaint']}, 05:05:53 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:05:53 INFO - 'tests': ['a11yr', 05:05:53 INFO - 'ts_paint', 05:05:53 INFO - 'tpaint', 05:05:53 INFO - 'sessionrestore', 05:05:53 INFO - 'sessionrestore_no_auto_restore', 05:05:53 INFO - 'tabpaint']}, 05:05:53 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:05:53 INFO - 'tests': ['a11yr', 05:05:53 INFO - 'ts_paint', 05:05:53 INFO - 'tpaint', 05:05:53 INFO - 'sessionrestore', 05:05:53 INFO - 'sessionrestore_no_auto_restore', 05:05:53 INFO - 'tabpaint']}, 05:05:53 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:05:53 INFO - 'tests': ['a11yr', 05:05:53 INFO - 'ts_paint', 05:05:53 INFO - 'tpaint', 05:05:53 INFO - 'sessionrestore', 05:05:53 INFO - 'sessionrestore_no_auto_restore', 05:05:53 INFO - 'tabpaint']}, 05:05:53 INFO - 'other_l64': {'tests': ['a11yr', 05:05:53 INFO - 'ts_paint', 05:05:53 INFO - 'tpaint', 05:05:53 INFO - 'sessionrestore', 05:05:53 INFO - 'sessionrestore_no_auto_restore', 05:05:53 INFO - 'tabpaint']}, 05:05:53 INFO - 'other_nol64': {'tests': ['a11yr', 05:05:53 INFO - 'ts_paint', 05:05:53 INFO - 'tpaint', 05:05:53 INFO - 'sessionrestore', 05:05:53 INFO - 'sessionrestore_no_auto_restore', 05:05:53 INFO - 'tabpaint']}, 05:05:53 INFO - 'svgr': {'tests': ['tsvgx', 05:05:53 INFO - 'tsvgr_opacity', 05:05:53 INFO - 'tart', 05:05:53 INFO - 'tscrollx', 05:05:53 INFO - 'cart']}, 05:05:53 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:05:53 INFO - 'tests': ['tsvgx', 05:05:53 INFO - 'tsvgr_opacity', 05:05:53 INFO - 'tart', 05:05:53 INFO - 'tscrollx', 05:05:53 INFO - 'cart']}, 05:05:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:53 INFO - 'tests': ['tp5o']}, 05:05:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:53 INFO - 'talos_options': ['--e10s'], 05:05:53 INFO - 'tests': ['tp5o']}, 05:05:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:53 INFO - 'talos_options': ['--xperf_path', 05:05:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:53 INFO - 'tests': ['tp5n']}, 05:05:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:53 INFO - 'talos_options': ['--e10s', 05:05:53 INFO - '--xperf_path', 05:05:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:53 INFO - 'tests': ['tp5n']}}, 05:05:53 INFO - 'talos.zip': {'path': '', 05:05:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:53 INFO - Running post-action listener: _resource_record_post_action 05:05:53 INFO - ##### 05:05:53 INFO - ##### Running create-virtualenv step. 05:05:53 INFO - ##### 05:05:53 INFO - Running pre-action listener: _resource_record_pre_action 05:05:53 INFO - Running main action method: create_virtualenv 05:05:53 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:53 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:05:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:53 INFO - Using real prefix '/tools/python27' 05:05:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:53 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:56 INFO - Installing pip.................done. 05:05:56 INFO - Return code: 0 05:05:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:56 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 0x1057e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b59a850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, '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.UejQCuNJec/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.d28Gtql7fj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:05:56 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:05:56 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:05:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/Render', 05:05:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:56 INFO - 'HOME': '/Users/cltbld', 05:05:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:56 INFO - 'LOGNAME': 'cltbld', 05:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:56 INFO - 'MOZ_NO_REMOTE': '1', 05:05:56 INFO - 'NO_EM_RESTART': '1', 05:05:56 INFO - 'PAGER': '/bin/cat', 05:05:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:56 INFO - 'PWD': '/builds/slave/test', 05:05:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:56 INFO - 'SHELL': '/bin/bash', 05:05:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d28Gtql7fj/Listeners', 05:05:56 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 05:05:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:56 INFO - 'USER': 'cltbld', 05:05:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:56 INFO - 'XPC_FLAGS': '0x0', 05:05:56 INFO - 'XPC_SERVICE_NAME': '0', 05:05:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:57 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:05:57 INFO - Cleaning up... 05:05:57 INFO - Return code: 0 05:05:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1057e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b59a850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, '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.UejQCuNJec/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.d28Gtql7fj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:05:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:05:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:05:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/Render', 05:05:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:57 INFO - 'HOME': '/Users/cltbld', 05:05:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:57 INFO - 'LOGNAME': 'cltbld', 05:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:57 INFO - 'MOZ_NO_REMOTE': '1', 05:05:57 INFO - 'NO_EM_RESTART': '1', 05:05:57 INFO - 'PAGER': '/bin/cat', 05:05:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:57 INFO - 'PWD': '/builds/slave/test', 05:05:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:57 INFO - 'SHELL': '/bin/bash', 05:05:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d28Gtql7fj/Listeners', 05:05:57 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 05:05:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:57 INFO - 'USER': 'cltbld', 05:05:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:57 INFO - 'XPC_FLAGS': '0x0', 05:05:57 INFO - 'XPC_SERVICE_NAME': '0', 05:05:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:57 INFO - Downloading/unpacking psutil>=0.7.1 05:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:06:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:00 INFO - Installing collected packages: psutil 05:06:00 INFO - Running setup.py install for psutil 05:06:00 INFO - building 'psutil._psutil_osx' extension 05:06:00 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:01 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:01 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:01 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:01 INFO - building 'psutil._psutil_posix' extension 05:06:01 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:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:06:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:06:01 INFO - ^ 05:06:01 INFO - 1 warning generated. 05:06:01 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:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:01 INFO - Successfully installed psutil 05:06:01 INFO - Cleaning up... 05:06:01 INFO - Return code: 0 05:06:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:06:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1057e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b59a850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, '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.UejQCuNJec/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.d28Gtql7fj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:06:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/Render', 05:06:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:01 INFO - 'HOME': '/Users/cltbld', 05:06:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:01 INFO - 'LOGNAME': 'cltbld', 05:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:01 INFO - 'MOZ_NO_REMOTE': '1', 05:06:01 INFO - 'NO_EM_RESTART': '1', 05:06:01 INFO - 'PAGER': '/bin/cat', 05:06:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:01 INFO - 'PWD': '/builds/slave/test', 05:06:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:01 INFO - 'SHELL': '/bin/bash', 05:06:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d28Gtql7fj/Listeners', 05:06:01 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 05:06:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:01 INFO - 'USER': 'cltbld', 05:06:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:01 INFO - 'XPC_FLAGS': '0x0', 05:06:01 INFO - 'XPC_SERVICE_NAME': '0', 05:06:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:06:04 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:04 INFO - Installing collected packages: mozsystemmonitor 05:06:04 INFO - Running setup.py install for mozsystemmonitor 05:06:04 INFO - Successfully installed mozsystemmonitor 05:06:04 INFO - Cleaning up... 05:06:04 INFO - Return code: 0 05:06:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1057e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b59a850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, '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.UejQCuNJec/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.d28Gtql7fj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:06:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/Render', 05:06:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:04 INFO - 'HOME': '/Users/cltbld', 05:06:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:04 INFO - 'LOGNAME': 'cltbld', 05:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:04 INFO - 'MOZ_NO_REMOTE': '1', 05:06:04 INFO - 'NO_EM_RESTART': '1', 05:06:04 INFO - 'PAGER': '/bin/cat', 05:06:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:04 INFO - 'PWD': '/builds/slave/test', 05:06:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:04 INFO - 'SHELL': '/bin/bash', 05:06:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d28Gtql7fj/Listeners', 05:06:04 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 05:06:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:04 INFO - 'USER': 'cltbld', 05:06:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:04 INFO - 'XPC_FLAGS': '0x0', 05:06:04 INFO - 'XPC_SERVICE_NAME': '0', 05:06:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:07 INFO - Downloading blobuploader-1.2.4.tar.gz 05:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:06:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:06:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:08 INFO - Downloading docopt-0.6.1.tar.gz 05:06:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:06:08 INFO - Installing collected packages: blobuploader, requests, docopt 05:06:08 INFO - Running setup.py install for blobuploader 05:06:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:06:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:06:08 INFO - Running setup.py install for requests 05:06:09 INFO - Running setup.py install for docopt 05:06:09 INFO - Successfully installed blobuploader requests docopt 05:06:09 INFO - Cleaning up... 05:06:09 INFO - Return code: 0 05:06:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:09 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:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:09 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:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:09 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 0x1057e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b59a850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, '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.UejQCuNJec/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.d28Gtql7fj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:09 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:09 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:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/Render', 05:06:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:09 INFO - 'HOME': '/Users/cltbld', 05:06:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:09 INFO - 'LOGNAME': 'cltbld', 05:06:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:09 INFO - 'MOZ_NO_REMOTE': '1', 05:06:09 INFO - 'NO_EM_RESTART': '1', 05:06:09 INFO - 'PAGER': '/bin/cat', 05:06:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:09 INFO - 'PWD': '/builds/slave/test', 05:06:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:09 INFO - 'SHELL': '/bin/bash', 05:06:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d28Gtql7fj/Listeners', 05:06:09 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 05:06:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:09 INFO - 'USER': 'cltbld', 05:06:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:09 INFO - 'XPC_FLAGS': '0x0', 05:06:09 INFO - 'XPC_SERVICE_NAME': '0', 05:06:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:09 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-ADqF56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:09 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-Kl63Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:09 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-B3NLFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:09 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-OYTU1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:10 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-chay2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:10 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-hW_Nkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:10 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-zO5Ebz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:10 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-E6FASQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:10 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-BV2qNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:10 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-F2x53f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:10 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-1EG_2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:10 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-a16IpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:10 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-I1GtbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:11 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-4wo4GT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:11 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-mEbQEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:11 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-ThnKt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:11 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-O72krW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:06:11 INFO - Running setup.py install for manifestparser 05:06:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:06:11 INFO - Running setup.py install for mozcrash 05:06:11 INFO - Running setup.py install for mozdebug 05:06:11 INFO - Running setup.py install for mozdevice 05:06:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:06:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:06:12 INFO - Running setup.py install for mozfile 05:06:12 INFO - Running setup.py install for mozhttpd 05:06:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:06:12 INFO - Running setup.py install for mozinfo 05:06:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:06:12 INFO - Running setup.py install for mozInstall 05:06:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:06:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:06:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:06:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:06:12 INFO - Running setup.py install for mozleak 05:06:12 INFO - Running setup.py install for mozlog 05:06:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:06:12 INFO - Running setup.py install for moznetwork 05:06:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:06:13 INFO - Running setup.py install for mozprocess 05:06:13 INFO - Running setup.py install for mozprofile 05:06:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:06:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:06:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:06:13 INFO - Running setup.py install for mozrunner 05:06:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:06:13 INFO - Running setup.py install for mozscreenshot 05:06:13 INFO - Running setup.py install for moztest 05:06:13 INFO - Running setup.py install for mozversion 05:06:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:06:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:06:13 INFO - Cleaning up... 05:06:13 INFO - Return code: 0 05:06:13 INFO - Installing None 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', '-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 0x1057e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b59a850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, '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.UejQCuNJec/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.d28Gtql7fj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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', '-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:13 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:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/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.d28Gtql7fj/Listeners', 05:06:13 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-rDJxDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:14 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:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-tg9Frc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:14 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:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-0EYCte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:14 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:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-QEUJOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:14 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:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-nd5_4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:14 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:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-Z5zuuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:14 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:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-dlp05s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:14 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:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:14 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-B6Dm8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-be9vIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-6EmMRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-sZPEAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-wZH6uQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-O_BZ8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-z2JU5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-n9Gdn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-oSRrIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:15 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-nSPsva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:16 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:16 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:16 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:16 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:16 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:16 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:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 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:18 INFO - Downloading blessings-1.6.tar.gz 05:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:18 INFO - Installing collected packages: blessings 05:06:18 INFO - Running setup.py install for blessings 05:06:18 INFO - Successfully installed blessings 05:06:18 INFO - Cleaning up... 05:06:18 INFO - Return code: 0 05:06:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:19 INFO - Reading from file tmpfile_stdout 05:06:19 INFO - Current package versions: 05:06:19 INFO - blessings == 1.6 05:06:19 INFO - blobuploader == 1.2.4 05:06:19 INFO - docopt == 0.6.1 05:06:19 INFO - manifestparser == 1.1 05:06:19 INFO - mozInstall == 1.12 05:06:19 INFO - mozcrash == 0.17 05:06:19 INFO - mozdebug == 0.1 05:06:19 INFO - mozdevice == 0.48 05:06:19 INFO - mozfile == 1.2 05:06:19 INFO - mozhttpd == 0.7 05:06:19 INFO - mozinfo == 0.9 05:06:19 INFO - mozleak == 0.1 05:06:19 INFO - mozlog == 3.1 05:06:19 INFO - moznetwork == 0.27 05:06:19 INFO - mozprocess == 0.22 05:06:19 INFO - mozprofile == 0.28 05:06:19 INFO - mozrunner == 6.11 05:06:19 INFO - mozscreenshot == 0.1 05:06:19 INFO - mozsystemmonitor == 0.0 05:06:19 INFO - moztest == 0.7 05:06:19 INFO - mozversion == 1.4 05:06:19 INFO - psutil == 3.1.1 05:06:19 INFO - requests == 1.2.3 05:06:19 INFO - wsgiref == 0.1.2 05:06:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:19 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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:19 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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:19 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 0x1057e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b59a850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, '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.UejQCuNJec/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.d28Gtql7fj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:19 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:19 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:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/Render', 05:06:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:19 INFO - 'HOME': '/Users/cltbld', 05:06:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:19 INFO - 'LOGNAME': 'cltbld', 05:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:19 INFO - 'MOZ_NO_REMOTE': '1', 05:06:19 INFO - 'NO_EM_RESTART': '1', 05:06:19 INFO - 'PAGER': '/bin/cat', 05:06:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:19 INFO - 'PWD': '/builds/slave/test', 05:06:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:19 INFO - 'SHELL': '/bin/bash', 05:06:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d28Gtql7fj/Listeners', 05:06:19 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 05:06:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:19 INFO - 'USER': 'cltbld', 05:06:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:19 INFO - 'XPC_FLAGS': '0x0', 05:06:19 INFO - 'XPC_SERVICE_NAME': '0', 05:06:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 INFO - Cleaning up... 05:06:19 INFO - Return code: 0 05:06:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:19 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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:19 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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:19 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 0x1057e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b59a850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, '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.UejQCuNJec/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.d28Gtql7fj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:19 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:19 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:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/Render', 05:06:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:19 INFO - 'HOME': '/Users/cltbld', 05:06:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:19 INFO - 'LOGNAME': 'cltbld', 05:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:19 INFO - 'MOZ_NO_REMOTE': '1', 05:06:19 INFO - 'NO_EM_RESTART': '1', 05:06:19 INFO - 'PAGER': '/bin/cat', 05:06:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:19 INFO - 'PWD': '/builds/slave/test', 05:06:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:19 INFO - 'SHELL': '/bin/bash', 05:06:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d28Gtql7fj/Listeners', 05:06:19 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 05:06:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:19 INFO - 'USER': 'cltbld', 05:06:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:19 INFO - 'XPC_FLAGS': '0x0', 05:06:19 INFO - 'XPC_SERVICE_NAME': '0', 05:06:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:19 INFO - Downloading/unpacking jsonschema 05:06:19 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:19 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:19 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:19 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:19 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:19 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:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:21 INFO - Downloading/unpacking functools32 (from jsonschema) 05:06:21 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:21 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:21 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:21 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:21 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:21 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:22 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:22 INFO - warning: no files found matching '*.txt' 05:06:22 INFO - no previously-included directories found matching 'build' 05:06:22 INFO - no previously-included directories found matching 'dist' 05:06:22 INFO - no previously-included directories found matching '.git*' 05:06:22 INFO - Installing collected packages: jsonschema, functools32 05:06:22 INFO - Running setup.py install for functools32 05:06:22 INFO - warning: no files found matching '*.txt' 05:06:22 INFO - no previously-included directories found matching 'build' 05:06:22 INFO - no previously-included directories found matching 'dist' 05:06:22 INFO - no previously-included directories found matching '.git*' 05:06:22 INFO - Successfully installed jsonschema functools32 05:06:22 INFO - Cleaning up... 05:06:22 INFO - Return code: 0 05:06:22 INFO - Installing flake8 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', '--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 0x1057e7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057ee4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe20b59a850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, '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.UejQCuNJec/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.d28Gtql7fj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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', '--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:22 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:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/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.d28Gtql7fj/Listeners', 05:06:22 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:22 INFO - Downloading/unpacking flake8 05:06:22 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:23 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:23 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:23 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:23 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:23 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 - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:06:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 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:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:06:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 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:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:06:26 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:26 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:26 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:26 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:26 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:26 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:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:06:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:26 INFO - no previously-included directories found matching 'docs/_build' 05:06:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:06:26 INFO - Running setup.py install for pep8 05:06:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:26 INFO - no previously-included directories found matching 'docs/_build' 05:06:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:06:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:06:26 INFO - Cleaning up... 05:06:27 INFO - Return code: 0 05:06:27 INFO - Running post-action listener: _resource_record_post_action 05:06:27 INFO - Running post-action listener: _start_resource_monitoring 05:06:27 INFO - Starting resource monitoring. 05:06:27 INFO - ##### 05:06:27 INFO - ##### Running install step. 05:06:27 INFO - ##### 05:06:27 INFO - Running pre-action listener: _resource_record_pre_action 05:06:27 INFO - Running main action method: install 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 - Detecting whether we're running mozinstall >=1.0... 05:06:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:27 INFO - Reading from file tmpfile_stdout 05:06:27 INFO - Output received: 05:06:27 INFO - Usage: mozinstall [options] installer 05:06:27 INFO - Options: 05:06:27 INFO - -h, --help show this help message and exit 05:06:27 INFO - -d DEST, --destination=DEST 05:06:27 INFO - Directory to install application into. [default: 05:06:27 INFO - "/builds/slave/test"] 05:06:27 INFO - --app=APP Application being installed. [default: firefox] 05:06:27 INFO - mkdir: /builds/slave/test/build/application 05:06:27 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:27 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:06:59 INFO - Reading from file tmpfile_stdout 05:06:59 INFO - Output received: 05:06:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:06:59 INFO - Running post-action listener: _resource_record_post_action 05:06:59 INFO - ##### 05:06:59 INFO - ##### Running run-tests step. 05:06:59 INFO - ##### 05:06:59 INFO - Running pre-action listener: _resource_record_pre_action 05:06:59 INFO - Running main action method: run_tests 05:06:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:59 INFO - 2016-04-07 05:06:59.500 screenresolution[1939:18208] starting screenresolution argv=screenresolution get 05:06:59 INFO - 2016-04-07 05:06:59.503 screenresolution[1939:18208] Display 0: 1600x1200x32@60 05:06:59 INFO - 2016-04-07 05:06:59.508 screenresolution[1940:18210] starting screenresolution argv=screenresolution list 05:06:59 INFO - Available Modes on Display 0 05:06:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:59 INFO - Intel Iris: 05:06:59 INFO - Chipset Model: Intel Iris 05:06:59 INFO - Type: GPU 05:06:59 INFO - Bus: Built-In 05:06:59 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:59 INFO - Vendor: Intel (0x8086) 05:06:59 INFO - Device ID: 0x0a2e 05:06:59 INFO - Revision ID: 0x0009 05:06:59 INFO - Displays: 05:06:59 INFO - Display: 05:06:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:59 INFO - Display Serial Number: 1600x1200 60 05:06:59 INFO - Main Display: Yes 05:06:59 INFO - Mirror: Off 05:06:59 INFO - Online: Yes 05:06:59 INFO - Rotation: Supported 05:06:59 INFO - Return code: 0 05:06:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:59 INFO - Python 2.7.3 05:06:59 INFO - Return code: 0 05:06:59 INFO - grabbing minidump binary from tooltool 05:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:59 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 0x10568bd30>, '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 0x1054efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105682b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:59 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:06:59 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:06:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:59 INFO - Return code: 0 05:06:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:06:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:06:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:07:01 INFO - Return code: 0 05:07:01 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', '--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-0047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:07:01 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 --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-0047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:07:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UejQCuNJec/Render', 05:07:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:01 INFO - 'HOME': '/Users/cltbld', 05:07:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:01 INFO - 'LOGNAME': 'cltbld', 05:07:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:07:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:01 INFO - 'MOZ_NO_REMOTE': '1', 05:07:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:07:01 INFO - 'NO_EM_RESTART': '1', 05:07:01 INFO - 'PAGER': '/bin/cat', 05:07:01 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:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:01 INFO - 'PWD': '/builds/slave/test', 05:07:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:07:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:01 INFO - 'SHELL': '/bin/bash', 05:07:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d28Gtql7fj/Listeners', 05:07:01 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 05:07:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:01 INFO - 'USER': 'cltbld', 05:07:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:01 INFO - 'XPC_FLAGS': '0x0', 05:07:01 INFO - 'XPC_SERVICE_NAME': '0', 05:07:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--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-0047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:07:01 INFO - mozversion application_buildid: 20160406205952 05:07:01 INFO - mozversion application_changeset: 2355485d9a8e09c7cfe47063bd192b72ec34d286 05:07:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:07:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:07:01 INFO - mozversion application_name: Firefox 05:07:01 INFO - mozversion application_remotingname: firefox-dev 05:07:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:07:01 INFO - mozversion application_vendor: Mozilla 05:07:01 INFO - mozversion application_version: 47.0a2 05:07:01 INFO - mozversion platform_buildid: 20160406205952 05:07:01 INFO - mozversion platform_changeset: 2355485d9a8e09c7cfe47063bd192b72ec34d286 05:07:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:07:01 INFO - mozversion platform_version: 47.0a2 05:07:01 INFO - using testdate: 1460030821 05:07:01 INFO - actual date: 1460030821 05:07:01 INFO - starting webserver on 'localhost:57089' 05:07:01 INFO - SUITE-START | Running 2 tests 05:07:01 INFO - TEST-START | dromaeo_css 05:07:01 INFO - operating with platform_type : mac_ 05:07:01 INFO - Initialising browser for dromaeo_css test... 05:07:01 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp6Qn3No/profile http://localhost:57089/getInfo.html) 05:07:03 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 05:07:03 INFO - PROCESS | 1958 | colorDepth:24 05:07:03 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 05:07:03 INFO - PROCESS | 1958 | __metrics 05:07:03 INFO - PROCESS | 1958 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:07:03 INFO - PROCESS | 1958 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:07:04 INFO - TEST-INFO | 1958: exit 0 05:07:04 INFO - Browser initialized. 05:07:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:07:04 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp6Qn3No/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:16 INFO - PROCESS | 1960 | RSS: Main: 207499264 05:07:16 INFO - PROCESS | 1960 | 05:08:07 INFO - PROCESS | 1960 | 1460030887198 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:16 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57089/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57089/tests/dromaeo/cssquery-ext.html) 05:10:18 INFO - PROCESS | 1960 | RSS: Main: 389943296 05:10:18 INFO - PROCESS | 1960 | 05:13:21 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57089/tests/dromaeo/cssquery-ext.html (next: http://localhost:57089/tests/dromaeo/cssquery-jquery.html) 05:13:21 INFO - PROCESS | 1960 | RSS: Main: 344514560 05:13:21 INFO - PROCESS | 1960 | 05:16:23 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57089/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57089/tests/dromaeo/cssquery-mootools.html) 05:16:24 INFO - PROCESS | 1960 | RSS: Main: 345853952 05:16:24 INFO - PROCESS | 1960 | 05:19:26 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57089/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57089/tests/dromaeo/cssquery-prototype.html) 05:19:27 INFO - PROCESS | 1960 | RSS: Main: 299757568 05:19:27 INFO - PROCESS | 1960 | 05:22:30 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57089/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57089/tests/dromaeo/cssquery-yui.html) 05:22:30 INFO - PROCESS | 1960 | RSS: Main: 305008640 05:22:30 INFO - PROCESS | 1960 | 05:25:33 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57089/tests/dromaeo/cssquery-yui.html (next: http://localhost:57089/tests/dromaeo/cssquery-dojo.html) 05:25:33 INFO - PROCESS | 1960 | RSS: Main: 351330304 05:25:33 INFO - PROCESS | 1960 | 05:25:33 INFO - PROCESS | 1960 | __start_tp_report 05:25:33 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 05:25:33 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 05:25:33 INFO - PROCESS | 1960 | |i|pagename|runs| 05:25:33 INFO - PROCESS | 1960 | |0;dojo.html;2820;3475.530803341548;3488.6860182583564;3505.7370697197716;3558.4306510958236;5711.171880077386;5724.513416359609;5732.541396688265;5767.36558978512;5791.21818554495;5772.797952071678;5797.246357973468;5806.158107074471;5813.273340832396;5819.330776960651;5076.086304465195;5128.461511541291;5157.252198431225;5238.109521381374;5263.394709608406;5178.249153872686;5188.6367954243215;5195.220716892478;5195.298634684326;5200.5059519345605;5170.819021334254;5179.404368497614;5189.455107213745;5224.791008359661;5228.137357336035;5184.974075129638;5218.712970786624;5223.6343455958095;5235.55497782689;5271.130263506517;4986.252062190664;5150.896982060375;5159.845204643867;5168.121419358718;5175.430702622709;5579.525740312079;5613.663180209201;5637.436256374371;5641.379448260688;5652.236948012048;6046.606970546901;6169.167162433059;6174.84562885927;6181.907271390933;6195;5147.305113809625;5796.82609521715;6187.335158123582;6190.96904515479;6238.9376106238815;5799.4780469757925;5801.216835727165;5823.708814559255;5825.88348233033;5834.941650583524;6171.938280617226;6212.689365531705;6322.178116844872;6322.557420980487;6336.52476064297;6163.753449861955;6184.196054512974;6202.44178023971;6209.875802484015;6220.595661282002;6220.968895155585;6227.719752611143;6230.626162430178;6230.875382492325;6255.24937007562;6100.72546735398;6108.281362586102;6137.140800287963;6186.938130618726;6198.690065496708;6160.9383906161265;6187.288461826947;6202.596831205957;6214.37856214384;6243;4897.534734200245;4900.534449227318;4907.705537667752;4910.803567857317;4940.110780059552;4351.804168812411;4361.978190109029;4423.380726698265;4439.089986552749;4448.065906159743;5785.1611516329895;5802.448767367094;5805.593608447452;5805.622634528742;5843.5325173985975;6188.597741146811;6195.721192546346;6198.783042593487;6199.721012554446;6222.62664240147;5654.717264136777;5716.142578613241;5740.225069615549;5766.365699773021;5782.913256301159;4572.314152877028;5355.357357117171;5381.704006279692;5393.379761327497;5421.972890135524;6349.111124442582;6355.269144048401;6357.332480089616;6360.681965901594;6379.362063793584;5646.616578938187;5829.883402331845;6117.279373342116;6193.84515387119;6195.938040619536;6341.587796793194;6359.14151589548;6367.808965731036;6377.7767778127545;6379.106925030406;6177.166082578794;6185.783497577563;6188.4739797117745;6188.659623720559;6192.102145188832;6101.60339577926;6147.600405973598;6199.721012554536;6203.193584834124;6215;5892.145638882424;6124.785632502841;6125.601835880653;6126.203593532812;6133.141360209664;6061.090836374579;6087.969560152171;6089.573729839046;6116.235470566179;6142.324344322032;6019.217501724747;6063.242094738158;6096.756129754937;6100.054491553817;6135.84660383494;6160.13758073861;6168.629882207082;6171.938280617316;6178.227721534809;6181.134641150333;5836.9416305838095;5969.283685957713;5979.983402821617;6054.969725151346;6080.8783824324155 05:25:33 INFO - PROCESS | 1960 | |1;ext.html;466.88880464494497;578.6846168837983;594.669958173214;673.9217252396165;674.9088873002144;28228.29429264267;40102.39846402306;40106;40214.1957160857;40258;42953.7852310738;43589.7820510897;43626;43689.59707216102;43730.562694373126;9548.809023819502;9794.41233525987;9795.804083918318;9801;9809.166220871235;9610.855837162448;9746.756331091708;9757.219422446187;9758.072983066597;9914.752131196741;9877.60489580416;9945.950270248639;9953.153981911546;9965.90034099657;9979.650712225037;9828.262880283972;9896.208303335716;9912.207023438108;9914.90085099147;9929.801403971953;9778.266630002778;9914.157296629795;9915.752106197402;9919;9921.801563968753;41493.58506414927;41828;41885.790571047255;41953;42316;3205.3108581655056;3297.7856439331367;3301.9174520636934;3375.51055097012;3703.777773333609;29344.826206951697;29449.852750736325;29875.551866722017;29881.701182988327;29943.951961681454;2754.600582915486;2762.5718013707906;2768.224897028835;2819.9295017624518;2857.2142660768245;490001;493178;494258;494708.52645736904;501857;71057;71451;71790;71985.64007180036;72040.27959720336;74962.5007499847;75062;75255.62372188104;75974.62012689901;77087;18056.006919619285;18205.453836384928;18219.908900455677;18235.270589176285;18258.36095736643;13654.522091726742;13730.588082357544;13750.243736594583;13764.449422023206;13793.379297931617;21970;22203.1118755248;22230.88884555567;22266.44333891633;22284.44288892758;7790.454668173174;9117.08829117083;9303.674371396968;9323.393979391318;9354.71935841926;925.6112432778249;947.303731757158;950.3775027357092;964.9228061755043;1183.9112066594867;23452.944617492252;24692.259232223063;26479.602805957926;29573.964911228435;30436.239094022825;1081.670090622352;1140.2075557487467;1153.7577108807077;1178.452019810792;1208.64949164743;14726;15111.773323400157;15142.772858407132;15375.154366509949;15390.769138462932;529213;533204;533976;534415;535537.3223133859;58612.706936465045;60226.397736022955;60274;60390.39609603848;60505.09242361368;1393.8675825796736;1420.843707192208;1502.2563830903773;1521.0873475914364;1527.8243002054905;25763.871180643975;28346.00788972376;28580.42839143164;29130.708692913646;29656.55515167231;37474.8126259367;46410;46787.29819052785;46831.06337873291;46997;317268;318466;318652.40673796483;318752;319767;27977;32258;33746.49380259249;34043.4893476603;34482;43762.56237437585;46938.76530617462;48276;48448;48795;35532.64467355293;37637.81181094077;37736;38038;38910.6108938907;37238.069048272926;37429.064273393386;37621.247575048896;37881.24237515289;38143.42784858285;37353.439698404;38087.428688570246;38667.41998869963;38701.80649096736;38788;32855.17862053468;33177;33633;34297.657023430445;34812.47781283232;22805.771942280364;24910.0035998558;25172.24483265505;25279.494410112064;25821.6126758095 05:25:33 INFO - PROCESS | 1960 | |2;jquery.html;381.32505465326375;384.29481900712193;385.05084965559905;385.62979539641924;390.59378246623515;20049.899750501245;20796.272130475438;21446.785532144673;21513.67729484059;21590.784092159072;20930.37208883736;21411;21570.02934867931;21900.780992190073;22219.666704999396;5829.0382086955815;5963.85090372742;6136.23297087864;6186.597871138384;6193.7832175873855;4081.7755023473696;4283.914321713564;5898.41015898411;6136.570440069197;6143.8771224575485;6109;6123.448889599935;6131.785387511438;6135.6625385603775;6143;4068.877933661995;4265.402843601898;5744.827655170331;5768.105943578752;6000.969995150018;5684.459976302286;5720.8855822883725;5723.484886360206;5727.713614319309;5732.570057245724;13592.728145437035;17746.112694365358;21220.575588488144;26248.475030499474;26327;2099.926502572418;2173.195917510531;2357.974281187689;2440.2923152285994;2581.4578938422956;8407.5375854328;9847.60609575616;9891.159251463634;9932.354396964247;10573.577056917791;1697.8387053229924;1751.5183324585842;1774.3612299572144;1792.363710882631;1845.3172326239244;84983;102430.48784756103;111277.44361278223;111743.44128279388;111754.44122779416;10273.02406271403;10578.723701754248;11110.44447777624;11568.421578921021;11675.76648467026;14118.788218176736;14195.361208745631;14229.928850355682;14248.358823852952;14253.144811311147;8886.289096872235;8931.151540603632;8945.284377249804;8976.506292153863;9015.81968360629;9164.221041211438;9192.95403522978;9209.263258939403;9270.582823772946;9290.535473226311;4501.909961800746;4516.458025037016;4536.478304994902;4559.088182363541;4577.771111444415;1606.3414000259838;1612.4517663994063;1790.7313902914407;1899.9810001899805;1910.455520176743;849.1084361420484;862.508370228976;965.652365148546;1009.6769033909226;1059.5814653211937;19793;21784.91075446253;21842.78157218439;21858.672119918214;21974.121035158736;899.9055099214619;988.6490295944836;1007.7581380468635;1039.3607931122306;1073.8550295710136;9353.672621458216;11405.258658187357;11580.942095289469;11678.532858685727;11718.824217636742;593268;593439;605275;610972;613712;97909.51045244728;113391;115330.4233478844;115537.8446215544;117484.41257793827;1023.1456733627505;1036.08306648615;1044.1490185498794;1056.8361903904906;1059.586761163147;18470.907645461688;18900.905495472434;19135;19148.042597870328;19472.318468853522;39056;50608.493915060375;52437.7378113107;53629.7318513405;54639;77484;77557.44885102379;78564.21435785569;80124.19875801167;80144.59927700325;6997.335253150943;8121.878171827546;8196.877046844183;8236.341092712688;8446;13230.933845331097;13383;13736.65658358549;13816.654583635489;13857.722845543234;9491.430514169171;11697.532098716296;11882.940585297018;11968;12023.699407514881;11722.94138529336;11795.233309834834;11874.643760687193;11892.405379730806;12073.758524829278;12001.1599188056;12073.215241009306;12113.33376664292;12168.634940951786;12280.447379867748;7588.924110758821;8176.959115204386;8230.382721295808;8485.57572121379;8510.234078932926;10983.890161098287;12010.519579216734;12159.270443773403;12178.634640960785;12188.45151968146 05:25:33 INFO - PROCESS | 1960 | |3;mootools.html;1471.8160229971254;1587.8570928616423;1611.9355225790966;1911.3788018893854;2051.2102840406446;8211.876821847669;8817.823643527134;9000.729978100651;9002.32482563807;9008.954955225232;8709.564521773918;8981.191692747652;8985.96661383941;8988.505632190227;8989.865152022709;2063.7204932941595;2204.173434961889;2520.4959008198343;2539.1747682003424;2542.5677634802128;2468.605023196289;2579.690437147545;2586.5990771430365;2588.809147792018;2589.6503971963825;2393;2425.7816796488314;2514.509670614231;2522.987385063072;2530.455951970326;2472.6043832986816;2480.3055144559553;2517.169334119736;2519.508695804319;2521.2562294123095;2235.8099561537288;2411.3248290478696;2539.212844018342;2574.5108429398356;2578.858162801045;8897.288216942628;8990.415622984485;8996.955015224947;9008.099190081006;9017.278617710568;4579.290210017452;5759.5968282220265;5762.222100016486;5765.1928729977835;5767.884642307173;3573.10672331917;3654.8720794772057;3722.32998060349;3726.888193354204;3738.12154143776;946.6841090883677;966.2511553546058;1082.3668154129844;1084.159776173472;1088.9654827913514;11922;12109.878901211052;12122.696932576668;12138.939305303506;12144.210626309261;4952;5000.699958002531;5105.923411148836;5108.540231379194;5115.948840511547;10606.363618182933;10618.946905265424;10626.256162068736;10631.627893023708;10632.893671063344;4772.308015337757;5680.289963754531;5716.714164291769;5733.627314224562;5740.885182296331;4496.662750293675;4676.95323046772;4939;5110.080185566634;5176.1459359206065;3742.5965263026364;4039.171969746195;4099.85650502237;4117.423560701504;4154.480689913761;1103.707517507861;1326.065124087516;1326.1645163547096;1332.9200247985152;1338.0432990411903;1208.975820483603;1346.7171893902391;1391.4921053815285;1410.6684929041667;1411.7388283167647;8316.625751841182;8419.326453883798;8422.620982055822;8440.957795211107;8447.226082522617;515.6596646213557;519.6518332717088;530.6842428754885;530.9469053094659;531.7367902888095;6291.591046582049;6323.588966717149;6366.490680745529;6377.394147556068;6385.329540398284;10554.313969592105;10930.125589952944;10944.671659850059;11052.336859788278;11065.50205240766;10521.579136834594;10801.459927003618;10832.675019749262;10834.783304333869;10902.945485272523;8409.579521024047;8505.23452889243;8536.44513106646;8563.143685631387;8569.442986206;8104.149064348158;8107.716229931924;8165.469244499089;8290.419670622998;8375;9374.203192728695;9641.662541811003;9778.41329520231;9825.508724563886;9826.803463930824;9698.660546880825;10041.648542301276;10074.395536267848;10235.897641023494;10247.231457640557;7641.0066691331995;7654.617269136632;7683.500572462772;7746.49647772893;7764.029496312961;8327.167283271867;8344.83310333802;8388.286995605198;8486.236238738544;8505.95747021261;7519.360854327443;7605.353544948742;7678.347340476123;7743.7676869694;7769.145394006768;7410.221926697619;7472.514286571355;7479.850402992019;7608.505447145918;7653;7989.640466179152;8039.87940180886;8071.4753541019645;8072.838543228985;8091.959540202261;7187.7843664690145;7235.457340699574;7254.419646428379;7294.525855819355;7298.379637730853;7190.712371505081;7194.856102878018;7239.746608868664;7242.6740796663225;7248.637568121679 05:25:33 INFO - PROCESS | 1960 | |4;prototype.html;328.39247392324205;331.9526892653677;337.88680791934695;338.2693382294245;341.0229691932611;6622.503312251582;6676.2989886062;6702.597844129344;6703.698333574988;6723.092382528357;6614.173228346457;6615.801525954229;6644;6723.881977177186;6799.08212391327;4650.023495066041;4774.379330687006;4819.7590120494015;4827.903441931159;4939.110960027194;3835.654791068803;4770.7376094314795;4815.807367705288;4848.151573474645;4902.3872015998;4851.345068415754;4854.538818812225;4866.902661946759;4873.390826146731;4879.585235255007;4851.660383773137;4868.318435419044;4870.22076467767;4873.19592267279;4896.1186986342445;3988.3618621020496;4047.1298670785627;4071.3282308419075;4074.9185016299507;4097.426360309559;6330;6361.9045714314325;6374.58565193261;6397.776077837253;6399.200099987502;5711.886182194474;5757.107648314518;5761.366249712528;5764.740586673568;5776.826695199151;7030.507864449491;7034.085568876065;7043.823904402379;7044.823879403005;7046.7181312747425;4353.499347575038;4667.533246675339;4683.157031734287;4688.109259240733;4708.293755936603;7391.963040184818;7423.628818559105;7481.513701609432;7494.587797671126;7521.022267105296;4649;4649.930251046169;4653.371794807657;4655.767211639472;4659.138059459011;4124;4526.841560545365;4721.173794586002;4734.053189362141;4735.337052812609;5593.608447408642;5601.579881508903;5607.579431542651;5607.691576963306;5623.943760562423;6832.145981752281;6841;7099.787006389816;7102.751403700846;7112.217656057829;1517.9696406072037;1612.9838701613069;1624.7400415933394;1632.5347276026275;1632.7387617981067;1024.8462730590322;1026.38416949831;1043.7129789307849;1045.6549338718205;1049.7585555322166;5621.634593751475;5700.144978253295;6499.805005849832;6536.901946470807;6666.633335166515;7039.753608623673;7160.928390716131;7187.64061796908;7375.483716139819;7438.5536867788105;6724.092247546519;6742.292059333699;6751.25736169021;6754.649070185884;6767.492438067165;3164.8734050637718;3830.1190726133145;3847.961520384816;3863.4011728182177;3870.303345397799;7397.926020739724;7644.923550764421;7655.693772249159;7667.194944530744;7674.501157424749;7520.435967302475;7616.80957976055;7620.8475830484185;7621.504602200839;7651.928729977808;7589.254471471592;7662.4253181011645;7667.961660191663;7670.884936725954;7677.9232207677205;7352.080989876265;7352.669129889275;7358.595277259802;7375.668094935633;7394.815129621803;7001.544899581511;7134.072570566001;7248.601326926859;7249;7259.20148783644;6132;6446.258680251831;7419.961205431136;7586.355159811478;7655.349295309961;4673.953260467351;4680.438347398334;4683.882902927454;4690.921088149813;4693.812247510062;7302.926970730225;7316.743913962986;7323.583217538093;7331.1569169544055;7334.193238743627;6834.829129271808;7087.82280442993;7095.184053833876;7099;7100.0414943981905;6945.895811562729;7057.153141622833;7059.682314295766;7069.929300706927;7078.327558881898;6328.936710633019;6862;6884.311568843075;6921.584704917721;6922.169339679271;6637;7064.717411303491;7071;7075.82310442243;7086.539374940612;6683.665816709243;6918.584884906922;6919.308069193041;6932.653367331714;6969.372756451944 05:25:33 INFO - PROCESS | 1960 | |5;yui.html;730.6858051038051;759.7986533568603;787.8030492376906;793.0562630469744;800.0839039300001;12477.438515266813;15350.46273380432;15546;15571.92214038931;15588.844111558896;12132.908038276553;14149.221792801392;14316.069455485385;14391.78412323816;14410.639734006654;775.3332134364442;787.6140691061368;948.3456415073595;963.7157038673573;970.3595626886256;955.4649396338039;981.4209616326366;982.415462799633;982.5578489679637;983.6163896080534;1017.8167929772638;1025.707673313106;1029.4183786160834;1032.71600309915;1033.3799720167915;811.5130921447114;821.8068753842902;825.8059356051323;1015.3197357770275;1016.486674229513;610.5024405109822;758.8254701418649;771.1170709537548;773.5668025905511;775.6974779835869;15042.398304067825;15121.924390378088;15131.621709457242;15142.54572362831;15158.31787569562;12822.974162067105;13046.54337098197;13141.145825521311;13311.800322995163;13568.05023648345;9781.90218097817;9896.703098907044;9910.504474776304;10189.898101019044;10294.17646588271;3327.218103745618;3536.8054756988354;3538.486919396699;3585.8027808470524;3786.129190286222;81410.5929470349;83163.58418207931;84889.72665410023;86094.56952715197;86274.56862715709;9548.570314335871;9695.854562181574;9954.153896918699;10007.249456290807;10100.494975251355;22524.873756312445;22527.21154759608;22556.210532631278;22568;22568.435789105402;7359.300866417681;7493.175750667422;7574.848503029909;7579.810504737426;7728.304452599181;4070.5522392536795;4088.12105397341;4100.34394496879;4136.8138433770555;4177.540470548237;3726.7018638508853;3726.7950262735267;3769.8115094245177;3784.2053168834304;3785.2051069275763;641.682367228225;645.3965542218041;653.3531803514486;655.1712084213474;655.2071992888658;504.82835835816;569.5898952754011;603.3363300369558;615.1203778596649;616.458446226432;7875.173106823816;7960.721374751972;8116.3172832744585;8199.42604017713;8257.79355516117;478.63384510849227;557.1364385202919;560.5258171010217;584.4009889862924;606.7633622887078;3742.4386342048365;3803;3805.961940380616;3820.8853734388013;4010.438538604598;74654;97256.0274397247;97556;98431;99058;82967.58516207381;83470;83960;84193.3161336782;84586.57706711427;73050.63474682592;73739.63130184315;74008.88986665204;74581.5083698323;74669.39979501559;8139.924319298541;8252.978020385044;8407.663693452194;8454.196851299117;8703.782405439915;50171.49828501814;51184;51560.96878062493;51792.482075180276;51909.740451297504;58062;58788.70605647116;60249.096263555206;71472.64263678648;72126.27873721195;10865.565377384815;10928.180386471206;11164.441777911234;11325.660230193107;11378.03286720638;11720.296782193096;12022.879771202526;12052.216605920938;12058.75882482363;12291.877081229073;10883.891161088604;11307.604233851776;11369.147313951322;11444.713882153013;11548.94225528867;11493.712657183638;11540.24988375753;11647.708807279885;11732.472038758106;11866.406679666155;10918.12654987615;11131.721706957067;11220.15848811326;11354.318740875575;11882.82175767347;9212.216961558343;9317.254619630548;9639.662611808553;9791.559379827782;9935.254855885982;9531.923324582584;9577.23382129442;9641.903580964101;9687.273454491082;9832.26258030665 05:25:33 INFO - PROCESS | 1960 | __end_tp_report 05:25:33 INFO - PROCESS | 1960 | __start_cc_report 05:25:33 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1664 05:25:33 INFO - PROCESS | 1960 | __end_cc_report 05:25:33 INFO - PROCESS | 1960 | __startTimestamp1460031933628__endTimestamp 05:25:33 INFO - PROCESS | 1960 | 05:25:33 INFO - PROCESS | 1960 | ------- Summary: start ------- 05:25:33 INFO - PROCESS | 1960 | Number of tests: 6 05:25:33 INFO - PROCESS | 1960 | 05:25:33 INFO - PROCESS | 1960 | [#0] dojo.html Cycles:165 Average:5732.63 Median:5979.98 stddev:647.45 (10.8%) stddev-sans-first:607.79 05:25:33 INFO - PROCESS | 1960 | Values: 2820.0 3475.5 3488.7 3505.7 3558.4 5711.2 5724.5 5732.5 5767.4 5791.2 5772.8 5797.2 5806.2 5813.3 5819.3 5076.1 5128.5 5157.3 5238.1 5263.4 5178.2 5188.6 5195.2 5195.3 5200.5 5170.8 5179.4 5189.5 5224.8 5228.1 5185.0 5218.7 5223.6 5235.6 5271.1 4986.3 5150.9 5159.8 5168.1 5175.4 5579.5 5613.7 5637.4 5641.4 5652.2 6046.6 6169.2 6174.8 6181.9 6195.0 5147.3 5796.8 6187.3 6191.0 6238.9 5799.5 5801.2 5823.7 5825.9 5834.9 6171.9 6212.7 6322.2 6322.6 6336.5 6163.8 6184.2 6202.4 6209.9 6220.6 6221.0 6227.7 6230.6 6230.9 6255.2 6100.7 6108.3 6137.1 6186.9 6198.7 6160.9 6187.3 6202.6 6214.4 6243.0 4897.5 4900.5 4907.7 4910.8 4940.1 4351.8 4362.0 4423.4 4439.1 4448.1 5785.2 5802.4 5805.6 5805.6 5843.5 6188.6 6195.7 6198.8 6199.7 6222.6 5654.7 5716.1 5740.2 5766.4 5782.9 4572.3 5355.4 5381.7 5393.4 5422.0 6349.1 6355.3 6357.3 6360.7 6379.4 5646.6 5829.9 6117.3 6193.8 6195.9 6341.6 6359.1 6367.8 6377.8 6379.1 6177.2 6185.8 6188.5 6188.7 6192.1 6101.6 6147.6 6199.7 6203.2 6215.0 5892.1 6124.8 6125.6 6126.2 6133.1 6061.1 6088.0 6089.6 6116.2 6142.3 6019.2 6063.2 6096.8 6100.1 6135.8 6160.1 6168.6 6171.9 6178.2 6181.1 5836.9 5969.3 5980.0 6055.0 6080.9 05:25:33 INFO - PROCESS | 1960 | 05:25:33 INFO - PROCESS | 1960 | [#1] ext.html Cycles:180 Average:61020.30 Median:38744.90 stddev:121887.75 (314.6%) stddev-sans-first:122144.89 05:25:33 INFO - PROCESS | 1960 | Values: 466.9 578.7 594.7 673.9 674.9 28228.3 40102.4 40106.0 40214.2 40258.0 42953.8 43589.8 43626.0 43689.6 43730.6 9548.8 9794.4 9795.8 9801.0 9809.2 9610.9 9746.8 9757.2 9758.1 9914.8 9877.6 9946.0 9953.2 9965.9 9979.7 9828.3 9896.2 9912.2 9914.9 9929.8 9778.3 9914.2 9915.8 9919.0 9921.8 41493.6 41828.0 41885.8 41953.0 42316.0 3205.3 3297.8 3301.9 3375.5 3703.8 29344.8 29449.9 29875.6 29881.7 29944.0 2754.6 2762.6 2768.2 2819.9 2857.2 490001.0 493178.0 494258.0 494708.5 501857.0 71057.0 71451.0 71790.0 71985.6 72040.3 74962.5 75062.0 75255.6 75974.6 77087.0 18056.0 18205.5 18219.9 18235.3 18258.4 13654.5 13730.6 13750.2 13764.4 13793.4 21970.0 22203.1 22230.9 22266.4 22284.4 7790.5 9117.1 9303.7 9323.4 9354.7 925.6 947.3 950.4 964.9 1183.9 23452.9 24692.3 26479.6 29574.0 30436.2 1081.7 1140.2 1153.8 1178.5 1208.6 14726.0 15111.8 15142.8 15375.2 15390.8 529213.0 533204.0 533976.0 534415.0 535537.3 58612.7 60226.4 60274.0 60390.4 60505.1 1393.9 1420.8 1502.3 1521.1 1527.8 25763.9 28346.0 28580.4 29130.7 29656.6 37474.8 46410.0 46787.3 46831.1 46997.0 317268.0 318466.0 318652.4 318752.0 319767.0 27977.0 32258.0 33746.5 34043.5 34482.0 43762.6 46938.8 48276.0 48448.0 48795.0 35532.6 37637.8 37736.0 38038.0 38910.6 37238.1 37429.1 37621.2 37881.2 38143.4 37353.4 38087.4 38667.4 38701.8 38788.0 32855.2 33177.0 33633.0 34297.7 34812.5 22805.8 24910.0 25172.2 25279.5 25821.6 05:25:33 INFO - PROCESS | 1960 | 05:25:33 INFO - PROCESS | 1960 | [#2] jquery.html Cycles:180 Average:34342.67 Median:22096.89 stddev:99993.49 (452.5%) stddev-sans-first:100241.48 05:25:33 INFO - PROCESS | 1960 | Values: 381.3 384.3 385.1 385.6 390.6 20049.9 20796.3 21446.8 21513.7 21590.8 20930.4 21411.0 21570.0 21900.8 22219.7 5829.0 5963.9 6136.2 6186.6 6193.8 4081.8 4283.9 5898.4 6136.6 6143.9 6109.0 6123.4 6131.8 6135.7 6143.0 4068.9 4265.4 5744.8 5768.1 6001.0 5684.5 5720.9 5723.5 5727.7 5732.6 13592.7 17746.1 21220.6 26248.5 26327.0 2099.9 2173.2 2358.0 2440.3 2581.5 8407.5 9847.6 9891.2 9932.4 10573.6 1697.8 1751.5 1774.4 1792.4 1845.3 84983.0 102430.5 111277.4 111743.4 111754.4 10273.0 10578.7 11110.4 11568.4 11675.8 14118.8 14195.4 14229.9 14248.4 14253.1 8886.3 8931.2 8945.3 8976.5 9015.8 9164.2 9193.0 9209.3 9270.6 9290.5 4501.9 4516.5 4536.5 4559.1 4577.8 1606.3 1612.5 1790.7 1900.0 1910.5 849.1 862.5 965.7 1009.7 1059.6 19793.0 21784.9 21842.8 21858.7 21974.1 899.9 988.6 1007.8 1039.4 1073.9 9353.7 11405.3 11580.9 11678.5 11718.8 593268.0 593439.0 605275.0 610972.0 613712.0 97909.5 113391.0 115330.4 115537.8 117484.4 1023.1 1036.1 1044.1 1056.8 1059.6 18470.9 18900.9 19135.0 19148.0 19472.3 39056.0 50608.5 52437.7 53629.7 54639.0 77484.0 77557.4 78564.2 80124.2 80144.6 6997.3 8121.9 8196.9 8236.3 8446.0 13230.9 13383.0 13736.7 13816.7 13857.7 9491.4 11697.5 11882.9 11968.0 12023.7 11722.9 11795.2 11874.6 11892.4 12073.8 12001.2 12073.2 12113.3 12168.6 12280.4 7588.9 8177.0 8230.4 8485.6 8510.2 10983.9 12010.5 12159.3 12178.6 12188.5 05:25:33 INFO - PROCESS | 1960 | 05:25:33 INFO - PROCESS | 1960 | [#3] mootools.html Cycles:180 Average:6199.87 Median:525.17 stddev:3296.79 (627.8%) stddev-sans-first:3286.88 05:25:33 INFO - PROCESS | 1960 | Values: 1471.8 1587.9 1611.9 1911.4 2051.2 8211.9 8817.8 9000.7 9002.3 9009.0 8709.6 8981.2 8986.0 8988.5 8989.9 2063.7 2204.2 2520.5 2539.2 2542.6 2468.6 2579.7 2586.6 2588.8 2589.7 2393.0 2425.8 2514.5 2523.0 2530.5 2472.6 2480.3 2517.2 2519.5 2521.3 2235.8 2411.3 2539.2 2574.5 2578.9 8897.3 8990.4 8997.0 9008.1 9017.3 4579.3 5759.6 5762.2 5765.2 5767.9 3573.1 3654.9 3722.3 3726.9 3738.1 946.7 966.3 1082.4 1084.2 1089.0 11922.0 12109.9 12122.7 12138.9 12144.2 4952.0 5000.7 5105.9 5108.5 5115.9 10606.4 10618.9 10626.3 10631.6 10632.9 4772.3 5680.3 5716.7 5733.6 5740.9 4496.7 4677.0 4939.0 5110.1 5176.1 3742.6 4039.2 4099.9 4117.4 4154.5 1103.7 1326.1 1326.2 1332.9 1338.0 1209.0 1346.7 1391.5 1410.7 1411.7 8316.6 8419.3 8422.6 8441.0 8447.2 515.7 519.7 530.7 530.9 531.7 6291.6 6323.6 6366.5 6377.4 6385.3 10554.3 10930.1 10944.7 11052.3 11065.5 10521.6 10801.5 10832.7 10834.8 10902.9 8409.6 8505.2 8536.4 8563.1 8569.4 8104.1 8107.7 8165.5 8290.4 8375.0 9374.2 9641.7 9778.4 9825.5 9826.8 9698.7 10041.6 10074.4 10235.9 10247.2 7641.0 7654.6 7683.5 7746.5 7764.0 8327.2 8344.8 8388.3 8486.2 8506.0 7519.4 7605.4 7678.3 7743.8 7769.1 7410.2 7472.5 7479.9 7608.5 7653.0 7989.6 8039.9 8071.5 8072.8 8092.0 7187.8 7235.5 7254.4 7294.5 7298.4 7190.7 7194.9 7239.7 7242.7 7248.6 05:25:33 INFO - PROCESS | 1960 | 05:25:33 INFO - PROCESS | 1960 | [#4] prototype.html Cycles:180 Average:5771.27 Median:6640.50 stddev:1859.58 (28.0%) stddev-sans-first:1819.37 05:25:33 INFO - PROCESS | 1960 | Values: 328.4 332.0 337.9 338.3 341.0 6622.5 6676.3 6702.6 6703.7 6723.1 6614.2 6615.8 6644.0 6723.9 6799.1 4650.0 4774.4 4819.8 4827.9 4939.1 3835.7 4770.7 4815.8 4848.2 4902.4 4851.3 4854.5 4866.9 4873.4 4879.6 4851.7 4868.3 4870.2 4873.2 4896.1 3988.4 4047.1 4071.3 4074.9 4097.4 6330.0 6361.9 6374.6 6397.8 6399.2 5711.9 5757.1 5761.4 5764.7 5776.8 7030.5 7034.1 7043.8 7044.8 7046.7 4353.5 4667.5 4683.2 4688.1 4708.3 7392.0 7423.6 7481.5 7494.6 7521.0 4649.0 4649.9 4653.4 4655.8 4659.1 4124.0 4526.8 4721.2 4734.1 4735.3 5593.6 5601.6 5607.6 5607.7 5623.9 6832.1 6841.0 7099.8 7102.8 7112.2 1518.0 1613.0 1624.7 1632.5 1632.7 1024.8 1026.4 1043.7 1045.7 1049.8 5621.6 5700.1 6499.8 6536.9 6666.6 7039.8 7160.9 7187.6 7375.5 7438.6 6724.1 6742.3 6751.3 6754.6 6767.5 3164.9 3830.1 3848.0 3863.4 3870.3 7397.9 7644.9 7655.7 7667.2 7674.5 7520.4 7616.8 7620.8 7621.5 7651.9 7589.3 7662.4 7668.0 7670.9 7677.9 7352.1 7352.7 7358.6 7375.7 7394.8 7001.5 7134.1 7248.6 7249.0 7259.2 6132.0 6446.3 7420.0 7586.4 7655.3 4674.0 4680.4 4683.9 4690.9 4693.8 7302.9 7316.7 7323.6 7331.2 7334.2 6834.8 7087.8 7095.2 7099.0 7100.0 6945.9 7057.2 7059.7 7069.9 7078.3 6328.9 6862.0 6884.3 6921.6 6922.2 6637.0 7064.7 7071.0 7075.8 7086.5 6683.7 6918.6 6919.3 6932.7 6969.4 05:25:33 INFO - PROCESS | 1960 | 05:25:33 INFO - PROCESS | 1960 | [#5] yui.html Cycles:180 Average:18711.63 Median:29315.79 stddev:26557.29 (90.6%) stddev-sans-first:26597.47 05:25:33 INFO - PROCESS | 1960 | Values: 730.7 759.8 787.8 793.1 800.1 12477.4 15350.5 15546.0 15571.9 15588.8 12132.9 14149.2 14316.1 14391.8 14410.6 775.3 787.6 948.3 963.7 970.4 955.5 981.4 982.4 982.6 983.6 1017.8 1025.7 1029.4 1032.7 1033.4 811.5 821.8 825.8 1015.3 1016.5 610.5 758.8 771.1 773.6 775.7 15042.4 15121.9 15131.6 15142.5 15158.3 12823.0 13046.5 13141.1 13311.8 13568.1 9781.9 9896.7 9910.5 10189.9 10294.2 3327.2 3536.8 3538.5 3585.8 3786.1 81410.6 83163.6 84889.7 86094.6 86274.6 9548.6 9695.9 9954.2 10007.2 10100.5 22524.9 22527.2 22556.2 22568.0 22568.4 7359.3 7493.2 7574.8 7579.8 7728.3 4070.6 4088.1 4100.3 4136.8 4177.5 3726.7 3726.8 3769.8 3784.2 3785.2 641.7 645.4 653.4 655.2 655.2 504.8 569.6 603.3 615.1 616.5 7875.2 7960.7 8116.3 8199.4 8257.8 478.6 557.1 560.5 584.4 606.8 3742.4 3803.0 3806.0 3820.9 4010.4 74654.0 97256.0 97556.0 98431.0 99058.0 82967.6 83470.0 83960.0 84193.3 84586.6 73050.6 73739.6 74008.9 74581.5 74669.4 8139.9 8253.0 8407.7 8454.2 8703.8 50171.5 51184.0 51561.0 51792.5 51909.7 58062.0 58788.7 60249.1 71472.6 72126.3 10865.6 10928.2 11164.4 11325.7 11378.0 11720.3 12022.9 12052.2 12058.8 12291.9 10883.9 11307.6 11369.1 11444.7 11548.9 11493.7 11540.2 11647.7 11732.5 11866.4 10918.1 11131.7 11220.2 11354.3 11882.8 9212.2 9317.3 9639.7 9791.6 9935.3 9531.9 9577.2 9641.9 9687.3 9832.3 05:25:33 INFO - PROCESS | 1960 | -------- Summary: end -------- 05:25:33 INFO - PROCESS | 1960 | 05:25:34 INFO - TEST-INFO | 1960: exit 0 05:25:34 INFO - TEST-OK | dromaeo_css | took 1112986ms 05:25:34 INFO - TEST-START | kraken 05:25:34 INFO - operating with platform_type : mac_ 05:25:34 INFO - Initialising browser for kraken test... 05:25:34 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp0OgHHZ/profile http://localhost:57089/getInfo.html) 05:25:36 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 05:25:36 INFO - PROCESS | 1965 | colorDepth:24 05:25:36 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 05:25:36 INFO - PROCESS | 1965 | __metrics 05:25:36 INFO - PROCESS | 1965 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:25:36 INFO - PROCESS | 1965 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:37 INFO - TEST-INFO | 1965: exit 0 05:25:37 INFO - Browser initialized. 05:25:37 INFO - Running cycle 1/1 for kraken test... 05:25:37 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmp0OgHHZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:49 INFO - PROCESS | 1967 | RSS: Main: 207826944 05:25:49 INFO - PROCESS | 1967 | 05:25:51 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57089/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:52 INFO - PROCESS | 1967 | RSS: Main: 262754304 05:25:52 INFO - PROCESS | 1967 | 05:25:54 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57089/tests/kraken/driver.html?testName=audio-dft) 05:25:55 INFO - PROCESS | 1967 | RSS: Main: 273166336 05:25:55 INFO - PROCESS | 1967 | 05:25:58 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57089/tests/kraken/driver.html?testName=audio-fft) 05:25:59 INFO - PROCESS | 1967 | RSS: Main: 262631424 05:25:59 INFO - PROCESS | 1967 | 05:26:01 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57089/tests/kraken/driver.html?testName=audio-oscillator) 05:26:01 INFO - PROCESS | 1967 | RSS: Main: 264003584 05:26:01 INFO - PROCESS | 1967 | 05:26:03 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57089/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:26:04 INFO - PROCESS | 1967 | RSS: Main: 264736768 05:26:04 INFO - PROCESS | 1967 | 05:26:07 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57089/tests/kraken/driver.html?testName=imaging-darkroom) 05:26:07 INFO - PROCESS | 1967 | RSS: Main: 264761344 05:26:07 INFO - PROCESS | 1967 | 05:26:10 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57089/tests/kraken/driver.html?testName=imaging-desaturate) 05:26:11 INFO - PROCESS | 1967 | RSS: Main: 264867840 05:26:11 INFO - PROCESS | 1967 | 05:26:13 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57089/tests/kraken/driver.html?testName=json-parse-financial) 05:26:14 INFO - PROCESS | 1967 | RSS: Main: 265179136 05:26:14 INFO - PROCESS | 1967 | 05:26:15 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57089/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:16 INFO - PROCESS | 1967 | RSS: Main: 297533440 05:26:16 INFO - PROCESS | 1967 | 05:26:18 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57089/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:18 INFO - PROCESS | 1967 | RSS: Main: 298545152 05:26:18 INFO - PROCESS | 1967 | 05:26:20 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57089/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:21 INFO - PROCESS | 1967 | RSS: Main: 303734784 05:26:21 INFO - PROCESS | 1967 | 05:26:24 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57089/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:24 INFO - PROCESS | 1967 | RSS: Main: 305422336 05:26:24 INFO - PROCESS | 1967 | 05:26:27 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57089/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:27 INFO - PROCESS | 1967 | RSS: Main: 305442816 05:26:27 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:57089/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57089/tests/kraken/driver.html?testName=ai-astar) 05:26:29 INFO - PROCESS | 1967 | RSS: Main: 367665152 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | __start_tp_report 05:26:29 INFO - PROCESS | 1967 | _x_x_mozilla_page_load 05:26:29 INFO - PROCESS | 1967 | _x_x_mozilla_page_load_details 05:26:29 INFO - PROCESS | 1967 | |i|pagename|runs| 05:26:29 INFO - PROCESS | 1967 | |0;ai-astar;110;109;111;111;112;112;119;110;111;111 05:26:29 INFO - PROCESS | 1967 | |1;audio-beat-detection;152;120;154;153;115;140;128;149;146;118 05:26:29 INFO - PROCESS | 1967 | |2;audio-dft;183;188;184;185;182;184;185;182;184;185 05:26:29 INFO - PROCESS | 1967 | |3;audio-fft;74;78;75;119;56;57;59;58;117;84 05:26:29 INFO - PROCESS | 1967 | |4;audio-oscillator;91;96;130;80;78;83;119;76;78;118 05:26:29 INFO - PROCESS | 1967 | |5;imaging-gaussian-blur;88;85;87;85;87;85;88;84;87;85 05:26:29 INFO - PROCESS | 1967 | |6;imaging-darkroom;93;93;92;92;93;92;93;94;93;92 05:26:29 INFO - PROCESS | 1967 | |7;imaging-desaturate;85;87;85;85;84;86;87;86;87;84 05:26:29 INFO - PROCESS | 1967 | |8;json-parse-financial;57;58;60;59;60;58;58;68;55;51 05:26:29 INFO - PROCESS | 1967 | |9;json-stringify-tinderbox;63;68;71;72;68;67;67;66;66;68 05:26:29 INFO - PROCESS | 1967 | |10;stanford-crypto-aes;80;83;81;83;81;78;78;80;83;84 05:26:29 INFO - PROCESS | 1967 | |11;stanford-crypto-ccm;121;122;133;134;107;120;117;134;113;121 05:26:29 INFO - PROCESS | 1967 | |12;stanford-crypto-pbkdf2;136;124;134;134;137;121;136;161;170;134 05:26:29 INFO - PROCESS | 1967 | |13;stanford-crypto-sha256-iterative;66;64;65;67;66;65;66;64;66;65 05:26:29 INFO - PROCESS | 1967 | __end_tp_report 05:26:29 INFO - PROCESS | 1967 | __start_cc_report 05:26:29 INFO - PROCESS | 1967 | _x_x_mozilla_cycle_collect,1256 05:26:29 INFO - PROCESS | 1967 | __end_cc_report 05:26:29 INFO - PROCESS | 1967 | __startTimestamp1460031989838__endTimestamp 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | ------- Summary: start ------- 05:26:29 INFO - PROCESS | 1967 | Number of tests: 14 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#0] ai-astar Cycles:10 Average:111.60 Median:111.00 stddev:2.76 (2.5%) stddev-sans-first:2.86 05:26:29 INFO - PROCESS | 1967 | Values: 110.0 109.0 111.0 111.0 112.0 112.0 119.0 110.0 111.0 111.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:147.50 stddev:15.69 (10.6%) stddev-sans-first:15.74 05:26:29 INFO - PROCESS | 1967 | Values: 152.0 120.0 154.0 153.0 115.0 140.0 128.0 149.0 146.0 118.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:1.75 (0.9%) stddev-sans-first:1.80 05:26:29 INFO - PROCESS | 1967 | Values: 183.0 188.0 184.0 185.0 182.0 184.0 185.0 182.0 184.0 185.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#3] audio-fft Cycles:10 Average:77.70 Median:66.50 stddev:23.45 (35.3%) stddev-sans-first:24.83 05:26:29 INFO - PROCESS | 1967 | Values: 74.0 78.0 75.0 119.0 56.0 57.0 59.0 58.0 117.0 84.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:79.00 stddev:20.15 (25.5%) stddev-sans-first:21.32 05:26:29 INFO - PROCESS | 1967 | Values: 91.0 96.0 130.0 80.0 78.0 83.0 119.0 76.0 78.0 118.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.45 (1.7%) stddev-sans-first:1.36 05:26:29 INFO - PROCESS | 1967 | Values: 88.0 85.0 87.0 85.0 87.0 85.0 88.0 84.0 87.0 85.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 05:26:29 INFO - PROCESS | 1967 | Values: 93.0 93.0 92.0 92.0 93.0 92.0 93.0 94.0 93.0 92.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.17 (1.4%) stddev-sans-first:1.22 05:26:29 INFO - PROCESS | 1967 | Values: 85.0 87.0 85.0 85.0 84.0 86.0 87.0 86.0 87.0 84.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:58.50 stddev:4.30 (7.4%) stddev-sans-first:4.53 05:26:29 INFO - PROCESS | 1967 | Values: 57.0 58.0 60.0 59.0 60.0 58.0 58.0 68.0 55.0 51.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:68.00 stddev:2.55 (3.7%) stddev-sans-first:2.09 05:26:29 INFO - PROCESS | 1967 | Values: 63.0 68.0 71.0 72.0 68.0 67.0 67.0 66.0 66.0 68.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:82.00 stddev:2.13 (2.6%) stddev-sans-first:2.22 05:26:29 INFO - PROCESS | 1967 | Values: 80.0 83.0 81.0 83.0 81.0 78.0 78.0 80.0 83.0 84.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#11] stanford-crypto-ccm Cycles:10 Average:122.20 Median:121.50 stddev:9.10 (7.5%) stddev-sans-first:9.64 05:26:29 INFO - PROCESS | 1967 | Values: 121.0 122.0 133.0 134.0 107.0 120.0 117.0 134.0 113.0 121.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.70 Median:136.00 stddev:15.24 (11.2%) stddev-sans-first:16.13 05:26:29 INFO - PROCESS | 1967 | Values: 136.0 124.0 134.0 134.0 137.0 121.0 136.0 161.0 170.0 134.0 05:26:29 INFO - PROCESS | 1967 | 05:26:29 INFO - PROCESS | 1967 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:1.00 05:26:29 INFO - PROCESS | 1967 | Values: 66.0 64.0 65.0 67.0 66.0 65.0 66.0 64.0 66.0 65.0 05:26:29 INFO - PROCESS | 1967 | -------- Summary: end -------- 05:26:29 INFO - PROCESS | 1967 | 05:26:30 INFO - TEST-INFO | 1967: exit 0 05:26:30 INFO - TEST-OK | kraken | took 56363ms 05:26:30 INFO - SUITE-END | took 1169s 05:26:31 INFO - Completed test suite (00:19:29) 05:26:31 INFO - javascript benchmark 05:26:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2820.0, 3475.530803341548, 3488.6860182583564, 3505.7370697197716, 3558.4306510958236, 5711.171880077386, 5724.513416359609, 5732.541396688265, 5767.36558978512, 5791.21818554495, 5772.797952071678, 5797.246357973468, 5806.158107074471, 5813.273340832396, 5819.330776960651, 5076.086304465195, 5128.461511541291, 5157.252198431225, 5238.109521381374, 5263.394709608406, 5178.249153872686, 5188.6367954243215, 5195.220716892478, 5195.298634684326, 5200.5059519345605, 5170.819021334254, 5179.404368497614, 5189.455107213745, 5224.791008359661, 5228.137357336035, 5184.974075129638, 5218.712970786624, 5223.6343455958095, 5235.55497782689, 5271.130263506517, 4986.252062190664, 5150.896982060375, 5159.845204643867, 5168.121419358718, 5175.430702622709, 5579.525740312079, 5613.663180209201, 5637.436256374371, 5641.379448260688, 5652.236948012048, 6046.606970546901, 6169.167162433059, 6174.84562885927, 6181.907271390933, 6195.0, 5147.305113809625, 5796.82609521715, 6187.335158123582, 6190.96904515479, 6238.9376106238815, 5799.4780469757925, 5801.216835727165, 5823.708814559255, 5825.88348233033, 5834.941650583524, 6171.938280617226, 6212.689365531705, 6322.178116844872, 6322.557420980487, 6336.52476064297, 6163.753449861955, 6184.196054512974, 6202.44178023971, 6209.875802484015, 6220.595661282002, 6220.968895155585, 6227.719752611143, 6230.626162430178, 6230.875382492325, 6255.24937007562, 6100.72546735398, 6108.281362586102, 6137.140800287963, 6186.938130618726, 6198.690065496708, 6160.9383906161265, 6187.288461826947, 6202.596831205957, 6214.37856214384, 6243.0, 4897.534734200245, 4900.534449227318, 4907.705537667752, 4910.803567857317, 4940.110780059552, 4351.804168812411, 4361.978190109029, 4423.380726698265, 4439.089986552749, 4448.065906159743, 5785.1611516329895, 5802.448767367094, 5805.593608447452, 5805.622634528742, 5843.5325173985975, 6188.597741146811, 6195.721192546346, 6198.783042593487, 6199.721012554446, 6222.62664240147, 5654.717264136777, 5716.142578613241, 5740.225069615549, 5766.365699773021, 5782.913256301159, 4572.314152877028, 5355.357357117171, 5381.704006279692, 5393.379761327497, 5421.972890135524, 6349.111124442582, 6355.269144048401, 6357.332480089616, 6360.681965901594, 6379.362063793584, 5646.616578938187, 5829.883402331845, 6117.279373342116, 6193.84515387119, 6195.938040619536, 6341.587796793194, 6359.14151589548, 6367.808965731036, 6377.7767778127545, 6379.106925030406, 6177.166082578794, 6185.783497577563, 6188.4739797117745, 6188.659623720559, 6192.102145188832, 6101.60339577926, 6147.600405973598, 6199.721012554536, 6203.193584834124, 6215.0, 5892.145638882424, 6124.785632502841, 6125.601835880653, 6126.203593532812, 6133.141360209664, 6061.090836374579, 6087.969560152171, 6089.573729839046, 6116.235470566179, 6142.324344322032, 6019.217501724747, 6063.242094738158, 6096.756129754937, 6100.054491553817, 6135.84660383494, 6160.13758073861, 6168.629882207082, 6171.938280617316, 6178.227721534809, 6181.134641150333, 5836.9416305838095, 5969.283685957713, 5979.983402821617, 6054.969725151346, 6080.8783824324155], "unit": "score", "name": "dojo.html", "value": 5690.797339338629}, {"lowerIsBetter": false, "replicates": [466.88880464494497, 578.6846168837983, 594.669958173214, 673.9217252396165, 674.9088873002144, 28228.29429264267, 40102.39846402306, 40106.0, 40214.1957160857, 40258.0, 42953.7852310738, 43589.7820510897, 43626.0, 43689.59707216102, 43730.562694373126, 9548.809023819502, 9794.41233525987, 9795.804083918318, 9801.0, 9809.166220871235, 9610.855837162448, 9746.756331091708, 9757.219422446187, 9758.072983066597, 9914.752131196741, 9877.60489580416, 9945.950270248639, 9953.153981911546, 9965.90034099657, 9979.650712225037, 9828.262880283972, 9896.208303335716, 9912.207023438108, 9914.90085099147, 9929.801403971953, 9778.266630002778, 9914.157296629795, 9915.752106197402, 9919.0, 9921.801563968753, 41493.58506414927, 41828.0, 41885.790571047255, 41953.0, 42316.0, 3205.3108581655056, 3297.7856439331367, 3301.9174520636934, 3375.51055097012, 3703.777773333609, 29344.826206951697, 29449.852750736325, 29875.551866722017, 29881.701182988327, 29943.951961681454, 2754.600582915486, 2762.5718013707906, 2768.224897028835, 2819.9295017624518, 2857.2142660768245, 490001.0, 493178.0, 494258.0, 494708.52645736904, 501857.0, 71057.0, 71451.0, 71790.0, 71985.64007180036, 72040.27959720336, 74962.5007499847, 75062.0, 75255.62372188104, 75974.62012689901, 77087.0, 18056.006919619285, 18205.453836384928, 18219.908900455677, 18235.270589176285, 18258.36095736643, 13654.522091726742, 13730.588082357544, 13750.243736594583, 13764.449422023206, 13793.379297931617, 21970.0, 22203.1118755248, 22230.88884555567, 22266.44333891633, 22284.44288892758, 7790.454668173174, 9117.08829117083, 9303.674371396968, 9323.393979391318, 9354.71935841926, 925.6112432778249, 947.303731757158, 950.3775027357092, 964.9228061755043, 1183.9112066594867, 23452.944617492252, 24692.259232223063, 26479.602805957926, 29573.964911228435, 30436.239094022825, 1081.670090622352, 1140.2075557487467, 1153.7577108807077, 1178.452019810792, 1208.64949164743, 14726.0, 15111.773323400157, 15142.772858407132, 15375.154366509949, 15390.769138462932, 529213.0, 533204.0, 533976.0, 534415.0, 535537.3223133859, 58612.706936465045, 60226.397736022955, 60274.0, 60390.39609603848, 60505.09242361368, 1393.8675825796736, 1420.843707192208, 1502.2563830903773, 1521.0873475914364, 1527.8243002054905, 25763.871180643975, 28346.00788972376, 28580.42839143164, 29130.708692913646, 29656.55515167231, 37474.8126259367, 46410.0, 46787.29819052785, 46831.06337873291, 46997.0, 317268.0, 318466.0, 318652.40673796483, 318752.0, 319767.0, 27977.0, 32258.0, 33746.49380259249, 34043.4893476603, 34482.0, 43762.56237437585, 46938.76530617462, 48276.0, 48448.0, 48795.0, 35532.64467355293, 37637.81181094077, 37736.0, 38038.0, 38910.6108938907, 37238.069048272926, 37429.064273393386, 37621.247575048896, 37881.24237515289, 38143.42784858285, 37353.439698404, 38087.428688570246, 38667.41998869963, 38701.80649096736, 38788.0, 32855.17862053468, 33177.0, 33633.0, 34297.657023430445, 34812.47781283232, 22805.771942280364, 24910.0035998558, 25172.24483265505, 25279.494410112064, 25821.6126758095], "unit": "score", "name": "ext.html", "value": 20144.79826243459}, {"lowerIsBetter": false, "replicates": [381.32505465326375, 384.29481900712193, 385.05084965559905, 385.62979539641924, 390.59378246623515, 20049.899750501245, 20796.272130475438, 21446.785532144673, 21513.67729484059, 21590.784092159072, 20930.37208883736, 21411.0, 21570.02934867931, 21900.780992190073, 22219.666704999396, 5829.0382086955815, 5963.85090372742, 6136.23297087864, 6186.597871138384, 6193.7832175873855, 4081.7755023473696, 4283.914321713564, 5898.41015898411, 6136.570440069197, 6143.8771224575485, 6109.0, 6123.448889599935, 6131.785387511438, 6135.6625385603775, 6143.0, 4068.877933661995, 4265.402843601898, 5744.827655170331, 5768.105943578752, 6000.969995150018, 5684.459976302286, 5720.8855822883725, 5723.484886360206, 5727.713614319309, 5732.570057245724, 13592.728145437035, 17746.112694365358, 21220.575588488144, 26248.475030499474, 26327.0, 2099.926502572418, 2173.195917510531, 2357.974281187689, 2440.2923152285994, 2581.4578938422956, 8407.5375854328, 9847.60609575616, 9891.159251463634, 9932.354396964247, 10573.577056917791, 1697.8387053229924, 1751.5183324585842, 1774.3612299572144, 1792.363710882631, 1845.3172326239244, 84983.0, 102430.48784756103, 111277.44361278223, 111743.44128279388, 111754.44122779416, 10273.02406271403, 10578.723701754248, 11110.44447777624, 11568.421578921021, 11675.76648467026, 14118.788218176736, 14195.361208745631, 14229.928850355682, 14248.358823852952, 14253.144811311147, 8886.289096872235, 8931.151540603632, 8945.284377249804, 8976.506292153863, 9015.81968360629, 9164.221041211438, 9192.95403522978, 9209.263258939403, 9270.582823772946, 9290.535473226311, 4501.909961800746, 4516.458025037016, 4536.478304994902, 4559.088182363541, 4577.771111444415, 1606.3414000259838, 1612.4517663994063, 1790.7313902914407, 1899.9810001899805, 1910.455520176743, 849.1084361420484, 862.508370228976, 965.652365148546, 1009.6769033909226, 1059.5814653211937, 19793.0, 21784.91075446253, 21842.78157218439, 21858.672119918214, 21974.121035158736, 899.9055099214619, 988.6490295944836, 1007.7581380468635, 1039.3607931122306, 1073.8550295710136, 9353.672621458216, 11405.258658187357, 11580.942095289469, 11678.532858685727, 11718.824217636742, 593268.0, 593439.0, 605275.0, 610972.0, 613712.0, 97909.51045244728, 113391.0, 115330.4233478844, 115537.8446215544, 117484.41257793827, 1023.1456733627505, 1036.08306648615, 1044.1490185498794, 1056.8361903904906, 1059.586761163147, 18470.907645461688, 18900.905495472434, 19135.0, 19148.042597870328, 19472.318468853522, 39056.0, 50608.493915060375, 52437.7378113107, 53629.7318513405, 54639.0, 77484.0, 77557.44885102379, 78564.21435785569, 80124.19875801167, 80144.59927700325, 6997.335253150943, 8121.878171827546, 8196.877046844183, 8236.341092712688, 8446.0, 13230.933845331097, 13383.0, 13736.65658358549, 13816.654583635489, 13857.722845543234, 9491.430514169171, 11697.532098716296, 11882.940585297018, 11968.0, 12023.699407514881, 11722.94138529336, 11795.233309834834, 11874.643760687193, 11892.405379730806, 12073.758524829278, 12001.1599188056, 12073.215241009306, 12113.33376664292, 12168.634940951786, 12280.447379867748, 7588.924110758821, 8176.959115204386, 8230.382721295808, 8485.57572121379, 8510.234078932926, 10983.890161098287, 12010.519579216734, 12159.270443773403, 12178.634640960785, 12188.45151968146], "unit": "score", "name": "jquery.html", "value": 9559.986797060103}, {"lowerIsBetter": false, "replicates": [1471.8160229971254, 1587.8570928616423, 1611.9355225790966, 1911.3788018893854, 2051.2102840406446, 8211.876821847669, 8817.823643527134, 9000.729978100651, 9002.32482563807, 9008.954955225232, 8709.564521773918, 8981.191692747652, 8985.96661383941, 8988.505632190227, 8989.865152022709, 2063.7204932941595, 2204.173434961889, 2520.4959008198343, 2539.1747682003424, 2542.5677634802128, 2468.605023196289, 2579.690437147545, 2586.5990771430365, 2588.809147792018, 2589.6503971963825, 2393.0, 2425.7816796488314, 2514.509670614231, 2522.987385063072, 2530.455951970326, 2472.6043832986816, 2480.3055144559553, 2517.169334119736, 2519.508695804319, 2521.2562294123095, 2235.8099561537288, 2411.3248290478696, 2539.212844018342, 2574.5108429398356, 2578.858162801045, 8897.288216942628, 8990.415622984485, 8996.955015224947, 9008.099190081006, 9017.278617710568, 4579.290210017452, 5759.5968282220265, 5762.222100016486, 5765.1928729977835, 5767.884642307173, 3573.10672331917, 3654.8720794772057, 3722.32998060349, 3726.888193354204, 3738.12154143776, 946.6841090883677, 966.2511553546058, 1082.3668154129844, 1084.159776173472, 1088.9654827913514, 11922.0, 12109.878901211052, 12122.696932576668, 12138.939305303506, 12144.210626309261, 4952.0, 5000.699958002531, 5105.923411148836, 5108.540231379194, 5115.948840511547, 10606.363618182933, 10618.946905265424, 10626.256162068736, 10631.627893023708, 10632.893671063344, 4772.308015337757, 5680.289963754531, 5716.714164291769, 5733.627314224562, 5740.885182296331, 4496.662750293675, 4676.95323046772, 4939.0, 5110.080185566634, 5176.1459359206065, 3742.5965263026364, 4039.171969746195, 4099.85650502237, 4117.423560701504, 4154.480689913761, 1103.707517507861, 1326.065124087516, 1326.1645163547096, 1332.9200247985152, 1338.0432990411903, 1208.975820483603, 1346.7171893902391, 1391.4921053815285, 1410.6684929041667, 1411.7388283167647, 8316.625751841182, 8419.326453883798, 8422.620982055822, 8440.957795211107, 8447.226082522617, 515.6596646213557, 519.6518332717088, 530.6842428754885, 530.9469053094659, 531.7367902888095, 6291.591046582049, 6323.588966717149, 6366.490680745529, 6377.394147556068, 6385.329540398284, 10554.313969592105, 10930.125589952944, 10944.671659850059, 11052.336859788278, 11065.50205240766, 10521.579136834594, 10801.459927003618, 10832.675019749262, 10834.783304333869, 10902.945485272523, 8409.579521024047, 8505.23452889243, 8536.44513106646, 8563.143685631387, 8569.442986206, 8104.149064348158, 8107.716229931924, 8165.469244499089, 8290.419670622998, 8375.0, 9374.203192728695, 9641.662541811003, 9778.41329520231, 9825.508724563886, 9826.803463930824, 9698.660546880825, 10041.648542301276, 10074.395536267848, 10235.897641023494, 10247.231457640557, 7641.0066691331995, 7654.617269136632, 7683.500572462772, 7746.49647772893, 7764.029496312961, 8327.167283271867, 8344.83310333802, 8388.286995605198, 8486.236238738544, 8505.95747021261, 7519.360854327443, 7605.353544948742, 7678.347340476123, 7743.7676869694, 7769.145394006768, 7410.221926697619, 7472.514286571355, 7479.850402992019, 7608.505447145918, 7653.0, 7989.640466179152, 8039.87940180886, 8071.4753541019645, 8072.838543228985, 8091.959540202261, 7187.7843664690145, 7235.457340699574, 7254.419646428379, 7294.525855819355, 7298.379637730853, 7190.712371505081, 7194.856102878018, 7239.746608868664, 7242.6740796663225, 7248.637568121679], "unit": "score", "name": "mootools.html", "value": 4954.0713966914955}, {"lowerIsBetter": false, "replicates": [328.39247392324205, 331.9526892653677, 337.88680791934695, 338.2693382294245, 341.0229691932611, 6622.503312251582, 6676.2989886062, 6702.597844129344, 6703.698333574988, 6723.092382528357, 6614.173228346457, 6615.801525954229, 6644.0, 6723.881977177186, 6799.08212391327, 4650.023495066041, 4774.379330687006, 4819.7590120494015, 4827.903441931159, 4939.110960027194, 3835.654791068803, 4770.7376094314795, 4815.807367705288, 4848.151573474645, 4902.3872015998, 4851.345068415754, 4854.538818812225, 4866.902661946759, 4873.390826146731, 4879.585235255007, 4851.660383773137, 4868.318435419044, 4870.22076467767, 4873.19592267279, 4896.1186986342445, 3988.3618621020496, 4047.1298670785627, 4071.3282308419075, 4074.9185016299507, 4097.426360309559, 6330.0, 6361.9045714314325, 6374.58565193261, 6397.776077837253, 6399.200099987502, 5711.886182194474, 5757.107648314518, 5761.366249712528, 5764.740586673568, 5776.826695199151, 7030.507864449491, 7034.085568876065, 7043.823904402379, 7044.823879403005, 7046.7181312747425, 4353.499347575038, 4667.533246675339, 4683.157031734287, 4688.109259240733, 4708.293755936603, 7391.963040184818, 7423.628818559105, 7481.513701609432, 7494.587797671126, 7521.022267105296, 4649.0, 4649.930251046169, 4653.371794807657, 4655.767211639472, 4659.138059459011, 4124.0, 4526.841560545365, 4721.173794586002, 4734.053189362141, 4735.337052812609, 5593.608447408642, 5601.579881508903, 5607.579431542651, 5607.691576963306, 5623.943760562423, 6832.145981752281, 6841.0, 7099.787006389816, 7102.751403700846, 7112.217656057829, 1517.9696406072037, 1612.9838701613069, 1624.7400415933394, 1632.5347276026275, 1632.7387617981067, 1024.8462730590322, 1026.38416949831, 1043.7129789307849, 1045.6549338718205, 1049.7585555322166, 5621.634593751475, 5700.144978253295, 6499.805005849832, 6536.901946470807, 6666.633335166515, 7039.753608623673, 7160.928390716131, 7187.64061796908, 7375.483716139819, 7438.5536867788105, 6724.092247546519, 6742.292059333699, 6751.25736169021, 6754.649070185884, 6767.492438067165, 3164.8734050637718, 3830.1190726133145, 3847.961520384816, 3863.4011728182177, 3870.303345397799, 7397.926020739724, 7644.923550764421, 7655.693772249159, 7667.194944530744, 7674.501157424749, 7520.435967302475, 7616.80957976055, 7620.8475830484185, 7621.504602200839, 7651.928729977808, 7589.254471471592, 7662.4253181011645, 7667.961660191663, 7670.884936725954, 7677.9232207677205, 7352.080989876265, 7352.669129889275, 7358.595277259802, 7375.668094935633, 7394.815129621803, 7001.544899581511, 7134.072570566001, 7248.601326926859, 7249.0, 7259.20148783644, 6132.0, 6446.258680251831, 7419.961205431136, 7586.355159811478, 7655.349295309961, 4673.953260467351, 4680.438347398334, 4683.882902927454, 4690.921088149813, 4693.812247510062, 7302.926970730225, 7316.743913962986, 7323.583217538093, 7331.1569169544055, 7334.193238743627, 6834.829129271808, 7087.82280442993, 7095.184053833876, 7099.0, 7100.0414943981905, 6945.895811562729, 7057.153141622833, 7059.682314295766, 7069.929300706927, 7078.327558881898, 6328.936710633019, 6862.0, 6884.311568843075, 6921.584704917721, 6922.169339679271, 6637.0, 7064.717411303491, 7071.0, 7075.82310442243, 7086.539374940612, 6683.665816709243, 6918.584884906922, 6919.308069193041, 6932.653367331714, 6969.372756451944], "unit": "score", "name": "prototype.html", "value": 5146.085897201329}, {"lowerIsBetter": false, "replicates": [730.6858051038051, 759.7986533568603, 787.8030492376906, 793.0562630469744, 800.0839039300001, 12477.438515266813, 15350.46273380432, 15546.0, 15571.92214038931, 15588.844111558896, 12132.908038276553, 14149.221792801392, 14316.069455485385, 14391.78412323816, 14410.639734006654, 775.3332134364442, 787.6140691061368, 948.3456415073595, 963.7157038673573, 970.3595626886256, 955.4649396338039, 981.4209616326366, 982.415462799633, 982.5578489679637, 983.6163896080534, 1017.8167929772638, 1025.707673313106, 1029.4183786160834, 1032.71600309915, 1033.3799720167915, 811.5130921447114, 821.8068753842902, 825.8059356051323, 1015.3197357770275, 1016.486674229513, 610.5024405109822, 758.8254701418649, 771.1170709537548, 773.5668025905511, 775.6974779835869, 15042.398304067825, 15121.924390378088, 15131.621709457242, 15142.54572362831, 15158.31787569562, 12822.974162067105, 13046.54337098197, 13141.145825521311, 13311.800322995163, 13568.05023648345, 9781.90218097817, 9896.703098907044, 9910.504474776304, 10189.898101019044, 10294.17646588271, 3327.218103745618, 3536.8054756988354, 3538.486919396699, 3585.8027808470524, 3786.129190286222, 81410.5929470349, 83163.58418207931, 84889.72665410023, 86094.56952715197, 86274.56862715709, 9548.570314335871, 9695.854562181574, 9954.153896918699, 10007.249456290807, 10100.494975251355, 22524.873756312445, 22527.21154759608, 22556.210532631278, 22568.0, 22568.435789105402, 7359.300866417681, 7493.175750667422, 7574.848503029909, 7579.810504737426, 7728.304452599181, 4070.5522392536795, 4088.12105397341, 4100.34394496879, 4136.8138433770555, 4177.540470548237, 3726.7018638508853, 3726.7950262735267, 3769.8115094245177, 3784.2053168834304, 3785.2051069275763, 641.682367228225, 645.3965542218041, 653.3531803514486, 655.1712084213474, 655.2071992888658, 504.82835835816, 569.5898952754011, 603.3363300369558, 615.1203778596649, 616.458446226432, 7875.173106823816, 7960.721374751972, 8116.3172832744585, 8199.42604017713, 8257.79355516117, 478.63384510849227, 557.1364385202919, 560.5258171010217, 584.4009889862924, 606.7633622887078, 3742.4386342048365, 3803.0, 3805.961940380616, 3820.8853734388013, 4010.438538604598, 74654.0, 97256.0274397247, 97556.0, 98431.0, 99058.0, 82967.58516207381, 83470.0, 83960.0, 84193.3161336782, 84586.57706711427, 73050.63474682592, 73739.63130184315, 74008.88986665204, 74581.5083698323, 74669.39979501559, 8139.924319298541, 8252.978020385044, 8407.663693452194, 8454.196851299117, 8703.782405439915, 50171.49828501814, 51184.0, 51560.96878062493, 51792.482075180276, 51909.740451297504, 58062.0, 58788.70605647116, 60249.096263555206, 71472.64263678648, 72126.27873721195, 10865.565377384815, 10928.180386471206, 11164.441777911234, 11325.660230193107, 11378.03286720638, 11720.296782193096, 12022.879771202526, 12052.216605920938, 12058.75882482363, 12291.877081229073, 10883.891161088604, 11307.604233851776, 11369.147313951322, 11444.713882153013, 11548.94225528867, 11493.712657183638, 11540.24988375753, 11647.708807279885, 11732.472038758106, 11866.406679666155, 10918.12654987615, 11131.721706957067, 11220.15848811326, 11354.318740875575, 11882.82175767347, 9212.216961558343, 9317.254619630548, 9639.662611808553, 9791.559379827782, 9935.254855885982, 9531.923324582584, 9577.23382129442, 9641.903580964101, 9687.273454491082, 9832.26258030665], "unit": "score", "name": "yui.html", "value": 7015.489913967074}], "name": "dromaeo_css", "value": 7621.743580207292}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 109.0, 111.0, 111.0, 112.0, 112.0, 119.0, 110.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.6}, {"lowerIsBetter": true, "replicates": [152.0, 120.0, 154.0, 153.0, 115.0, 140.0, 128.0, 149.0, 146.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 137.5}, {"lowerIsBetter": true, "replicates": [183.0, 188.0, 184.0, 185.0, 182.0, 184.0, 185.0, 182.0, 184.0, 185.0], "unit": "score", "name": "audio-dft", "value": 184.2}, {"lowerIsBetter": true, "replicates": [74.0, 78.0, 75.0, 119.0, 56.0, 57.0, 59.0, 58.0, 117.0, 84.0], "unit": "score", "name": "audio-fft", "value": 77.7}, {"lowerIsBetter": true, "replicates": [91.0, 96.0, 130.0, 80.0, 78.0, 83.0, 119.0, 76.0, 78.0, 118.0], "unit": "score", "name": "audio-oscillator", "value": 94.9}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 87.0, 85.0, 87.0, 85.0, 88.0, 84.0, 87.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.1}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 92.0, 92.0, 93.0, 92.0, 93.0, 94.0, 93.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 92.7}, {"lowerIsBetter": true, "replicates": [85.0, 87.0, 85.0, 85.0, 84.0, 86.0, 87.0, 86.0, 87.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 85.6}, {"lowerIsBetter": true, "replicates": [57.0, 58.0, 60.0, 59.0, 60.0, 58.0, 58.0, 68.0, 55.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 58.4}, {"lowerIsBetter": true, "replicates": [63.0, 68.0, 71.0, 72.0, 68.0, 67.0, 67.0, 66.0, 66.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.6}, {"lowerIsBetter": true, "replicates": [80.0, 83.0, 81.0, 83.0, 81.0, 78.0, 78.0, 80.0, 83.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.1}, {"lowerIsBetter": true, "replicates": [121.0, 122.0, 133.0, 134.0, 107.0, 120.0, 117.0, 134.0, 113.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.2}, {"lowerIsBetter": true, "replicates": [136.0, 124.0, 134.0, 134.0, 137.0, 121.0, 136.0, 161.0, 170.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 138.7}, {"lowerIsBetter": true, "replicates": [66.0, 64.0, 65.0, 67.0, 66.0, 65.0, 66.0, 64.0, 66.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.4}], "name": "kraken", "value": 1403.7000000000003}]} 05:26:31 INFO - Return code: 0 05:26:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:26:31 INFO - # TBPL SUCCESS # 05:26:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:31 INFO - 2016-04-07 05:26:31.154 screenresolution[1971:20189] starting screenresolution argv=screenresolution get 05:26:31 INFO - 2016-04-07 05:26:31.157 screenresolution[1971:20189] Display 0: 1600x1200x32@60 05:26:31 INFO - 2016-04-07 05:26:31.163 screenresolution[1972:20192] starting screenresolution argv=screenresolution list 05:26:31 INFO - Available Modes on Display 0 05:26:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:31 INFO - Intel Iris: 05:26:31 INFO - Chipset Model: Intel Iris 05:26:31 INFO - Type: GPU 05:26:31 INFO - Bus: Built-In 05:26:31 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:31 INFO - Vendor: Intel (0x8086) 05:26:31 INFO - Device ID: 0x0a2e 05:26:31 INFO - Revision ID: 0x0009 05:26:31 INFO - Displays: 05:26:31 INFO - Display: 05:26:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:31 INFO - Display Serial Number: 1600x1200 60 05:26:31 INFO - Main Display: Yes 05:26:31 INFO - Mirror: Off 05:26:31 INFO - Online: Yes 05:26:31 INFO - Rotation: Supported 05:26:31 INFO - Return code: 0 05:26:31 INFO - Running post-action listener: _resource_record_post_action 05:26:31 INFO - Running post-run listener: _resource_record_post_run 05:26:32 INFO - Total resource usage - Wall time: 1203s; CPU: 52.0%; Read bytes: 1191936; Write bytes: 663611904; Read time: 10; Write time: 1858 05:26:32 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 358222336; Write bytes: 354595328; Read time: 27976; Write time: 672 05:26:32 INFO - run-tests - Wall time: 1172s; CPU: 52.0%; Read bytes: 1191936; Write bytes: 297842688; Read time: 10; Write time: 1150 05:26:32 INFO - Running post-run listener: _upload_blobber_files 05:26:32 INFO - Blob upload gear active. 05:26:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:32 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:32 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:32 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:32 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:26:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:26:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:34 INFO - (blobuploader) - INFO - Done attempting. 05:26:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:26:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:35 INFO - (blobuploader) - INFO - Done attempting. 05:26:35 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:35 INFO - Return code: 0 05:26:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9584f182d8148bc00d7a1383da330b7cd1bd1762534af0e5088cd3e7cdff22d4481f894b59e253171d2fdb746037955842192b255496cf8c3cde266094dcd5b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4b4987a2dd51b98ac06a501f2b3259979df690bc9ec78a54811099b22d9f138f41623e6c99d1ad5d2150488ada0fab1598a3959a8bad1b2615a94caf069724e"} 05:26:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:35 INFO - Contents: 05:26:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9584f182d8148bc00d7a1383da330b7cd1bd1762534af0e5088cd3e7cdff22d4481f894b59e253171d2fdb746037955842192b255496cf8c3cde266094dcd5b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4b4987a2dd51b98ac06a501f2b3259979df690bc9ec78a54811099b22d9f138f41623e6c99d1ad5d2150488ada0fab1598a3959a8bad1b2615a94caf069724e"} 05:26:35 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:35 INFO - Copying logs to upload dir... 05:26:35 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.133765 ========= master_lag: 2.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-04-07 05:26:37.325679) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:37.330367) ========= 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.UejQCuNJec/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.d28Gtql7fj/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9584f182d8148bc00d7a1383da330b7cd1bd1762534af0e5088cd3e7cdff22d4481f894b59e253171d2fdb746037955842192b255496cf8c3cde266094dcd5b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4b4987a2dd51b98ac06a501f2b3259979df690bc9ec78a54811099b22d9f138f41623e6c99d1ad5d2150488ada0fab1598a3959a8bad1b2615a94caf069724e"} 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.011368 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9584f182d8148bc00d7a1383da330b7cd1bd1762534af0e5088cd3e7cdff22d4481f894b59e253171d2fdb746037955842192b255496cf8c3cde266094dcd5b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4b4987a2dd51b98ac06a501f2b3259979df690bc9ec78a54811099b22d9f138f41623e6c99d1ad5d2150488ada0fab1598a3959a8bad1b2615a94caf069724e"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:37.395059) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:37.395405) ========= 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.UejQCuNJec/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.d28Gtql7fj/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.005080 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:37.490581) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:26:37.490889) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:26:37.491776) ========= ========= Total master_lag: 2.38 =========