builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0049 starttime: 1458094413.51 results: success (0) buildid: 20160315170242 builduid: 64b174d4b0a346dba7ee2e19714a018a revision: 55e4902a0ea4abe5f7d50a17a3b540b378b344ba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.511997) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.512397) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.512684) ========= 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.xwxsBGSoXk/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.JafRscLBbs/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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.005022 basedir: '/builds/slave/test' ========= master_lag: 0.11 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.632342) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.632618) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.658983) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.659259) ========= 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.xwxsBGSoXk/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.JafRscLBbs/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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.005483 ========= master_lag: 0.24 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.906892) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.907194) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.907541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:33.907813) ========= 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.xwxsBGSoXk/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.JafRscLBbs/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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-03-15 19:13:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.4M=0.001s 2016-03-15 19:13:34 (17.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.152633 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:34.248735) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:34.249072) ========= 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.xwxsBGSoXk/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.JafRscLBbs/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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.026160 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:34.298709) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-15 19:13:34.299118) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 55e4902a0ea4abe5f7d50a17a3b540b378b344ba --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 55e4902a0ea4abe5f7d50a17a3b540b378b344ba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xwxsBGSoXk/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.JafRscLBbs/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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-03-15 19:13:34,375 truncating revision to first 12 chars 2016-03-15 19:13:34,375 Setting DEBUG logging. 2016-03-15 19:13:34,375 attempt 1/10 2016-03-15 19:13:34,375 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/55e4902a0ea4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-15 19:13:36,018 unpacking tar archive at: mozilla-central-55e4902a0ea4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.887011 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-15 19:13:36.244466) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:36.244959) ========= echo 55e4902a0ea4abe5f7d50a17a3b540b378b344ba in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'55e4902a0ea4abe5f7d50a17a3b540b378b344ba'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xwxsBGSoXk/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.JafRscLBbs/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 55e4902a0ea4abe5f7d50a17a3b540b378b344ba program finished with exit code 0 elapsedTime=0.005457 script_repo_revision: '55e4902a0ea4abe5f7d50a17a3b540b378b344ba' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:36.270433) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:36.271161) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-15 19:13:36.287982) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2016-03-15 19:13:36.288736) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xwxsBGSoXk/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.JafRscLBbs/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:13:36 INFO - MultiFileLogger online at 20160315 19:13:36 in /builds/slave/test 19:13:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:13:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:13:36 INFO - {'append_to_log': False, 19:13:36 INFO - 'base_work_dir': '/builds/slave/test', 19:13:36 INFO - 'blob_upload_branch': 'Firefox', 19:13:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:13:36 INFO - 'branch': 'Firefox', 19:13:36 INFO - 'buildbot_json_path': 'buildprops.json', 19:13:36 INFO - 'config_files': ('talos/mac_config.py',), 19:13:36 INFO - 'default_actions': ('clobber', 19:13:36 INFO - 'read-buildbot-config', 19:13:36 INFO - 'download-and-extract', 19:13:36 INFO - 'populate-webroot', 19:13:36 INFO - 'create-virtualenv', 19:13:36 INFO - 'install', 19:13:36 INFO - 'run-tests'), 19:13:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:13:36 INFO - 'download_minidump_stackwalk': True, 19:13:36 INFO - 'download_symbols': 'ondemand', 19:13:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:13:36 INFO - 'tooltool.py': '/tools/tooltool.py', 19:13:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:13:36 INFO - '/tools/misc-python/virtualenv.py')}, 19:13:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:13:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:13:36 INFO - 'installer_path': 'installer.exe', 19:13:36 INFO - 'log_level': 'info', 19:13:36 INFO - 'log_name': 'talos', 19:13:36 INFO - 'log_to_console': True, 19:13:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:13:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:13:36 INFO - 'opt_config_files': (), 19:13:36 INFO - 'pip_index': False, 19:13:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:13:36 INFO - 'cmd': ('bash', 19:13:36 INFO - '-c', 19:13:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:13:36 INFO - 'enabled': True, 19:13:36 INFO - 'halt_on_failure': False, 19:13:36 INFO - 'name': 'check_screen_resolution'},), 19:13:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:13:36 INFO - 'cmd': ('bash', 19:13:36 INFO - '-c', 19:13:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:13:36 INFO - 'enabled': True, 19:13:36 INFO - 'halt_on_failure': False, 19:13:36 INFO - 'name': 'check_screen_resolution'},), 19:13:36 INFO - 'run_cmd_checks_enabled': True, 19:13:36 INFO - 'sps_profile': False, 19:13:36 INFO - 'sps_profile_interval': 0, 19:13:36 INFO - 'suite': 'dromaeojs', 19:13:36 INFO - 'system_bits': '32', 19:13:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:13:36 INFO - 'title': 't-yosemite-r7-0049', 19:13:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:13:36 INFO - 'use_talos_json': True, 19:13:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:13:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:13:36 INFO - 'work_dir': 'build'} 19:13:36 INFO - ##### 19:13:36 INFO - ##### Running clobber step. 19:13:36 INFO - ##### 19:13:36 INFO - Running pre-action listener: _resource_record_pre_action 19:13:36 INFO - Running main action method: clobber 19:13:36 INFO - rmtree: /builds/slave/test/build 19:13:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:13:38 INFO - Running post-action listener: _resource_record_post_action 19:13:38 INFO - ##### 19:13:38 INFO - ##### Running read-buildbot-config step. 19:13:38 INFO - ##### 19:13:38 INFO - Running pre-action listener: _resource_record_pre_action 19:13:38 INFO - Running main action method: read_buildbot_config 19:13:38 INFO - Using buildbot properties: 19:13:38 INFO - { 19:13:38 INFO - "project": "", 19:13:38 INFO - "product": "firefox", 19:13:38 INFO - "script_repo_revision": "production", 19:13:38 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:13:38 INFO - "repository": "", 19:13:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:13:38 INFO - "buildid": "20160315170242", 19:13:38 INFO - "pgo_build": "False", 19:13:38 INFO - "basedir": "/builds/slave/test", 19:13:38 INFO - "buildnumber": 166, 19:13:38 INFO - "slavename": "t-yosemite-r7-0049", 19:13:38 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:13:38 INFO - "platform": "yosemite_r7", 19:13:38 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:13:38 INFO - "branch": "mozilla-central", 19:13:38 INFO - "repo_path": "mozilla-central", 19:13:38 INFO - "revision": "55e4902a0ea4abe5f7d50a17a3b540b378b344ba", 19:13:38 INFO - "stage_platform": "macosx64", 19:13:38 INFO - "builduid": "64b174d4b0a346dba7ee2e19714a018a", 19:13:38 INFO - "slavebuilddir": "test" 19:13:38 INFO - } 19:13:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 19:13:38 INFO - Running post-action listener: _resource_record_post_action 19:13:38 INFO - ##### 19:13:38 INFO - ##### Running download-and-extract step. 19:13:38 INFO - ##### 19:13:38 INFO - Running pre-action listener: _resource_record_pre_action 19:13:38 INFO - Running main action method: download_and_extract 19:13:38 INFO - mkdir: /builds/slave/test/build/tests 19:13:38 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')]} 19:13:38 INFO - https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:13:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 19:13:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 19:13:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 19:13:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 19:13:40 INFO - Downloaded 1199 bytes. 19:13:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 19:13:40 INFO - Using the following test package requirements: 19:13:40 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 19:13:40 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 19:13:40 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 19:13:40 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 19:13:40 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 19:13:40 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 19:13:40 INFO - u'jsshell-mac.zip'], 19:13:40 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 19:13:40 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 19:13:40 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 19:13:40 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 19:13:40 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 19:13:40 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 19:13:40 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 19:13:40 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 19:13:40 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 19:13:40 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 19:13:40 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 19:13:40 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:13:40 INFO - https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:13:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 19:13:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 19:13:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 19:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:13:43 INFO - Downloaded 17853784 bytes. 19:13:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:13:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:13:46 INFO - Return code: 0 19:13:46 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:13: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')]} 19:13:46 INFO - https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:13:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 19:13:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 19:13:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 19:13:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:13:47 INFO - Downloaded 17853784 bytes. 19:13:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:13:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:13:51 INFO - Return code: 0 19:13: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')]} 19:13:51 INFO - https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:13:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 19:13:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 19:13:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 19:13:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:13:52 INFO - Downloaded 10845263 bytes. 19:13:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:13:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:13:52 INFO - Return code: 0 19:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:13:52 INFO - https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:13:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 19:13:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 19:13:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 19:13:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 19:13:55 INFO - Downloaded 111196729 bytes. 19:13:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 19:13:55 INFO - mkdir: /builds/slave/test/properties 19:13:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:13:55 INFO - Writing to file /builds/slave/test/properties/build_url 19:13:55 INFO - Contents: 19:13:55 INFO - build_url:https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 19:13:56 INFO - Running post-action listener: _resource_record_post_action 19:13:56 INFO - Running post-action listener: set_extra_try_arguments 19:13:56 INFO - ##### 19:13:56 INFO - ##### Running populate-webroot step. 19:13:56 INFO - ##### 19:13:56 INFO - Running pre-action listener: _resource_record_pre_action 19:13:56 INFO - Running main action method: populate_webroot 19:13:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:13:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:13:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:13:56 INFO - '--tppagecycles', 19:13:56 INFO - '7'], 19:13:56 INFO - 'tests': ['tsvgm']}}, 19:13:56 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 19:13:56 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 19:13:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:13:56 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 19:13:56 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 19:13:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:13:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:13:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:13:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:13:56 INFO - 'talos_options': ['--e10s'], 19:13:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:13:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:13:56 INFO - 'tests': ['damp', 'tps']}, 19:13:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:13:56 INFO - 'talos_options': ['--e10s'], 19:13:56 INFO - 'tests': ['damp', 'tps']}, 19:13:56 INFO - 'g3': {'tests': ['dromaeo_dom']}, 19:13:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:13:56 INFO - 'other': {'tests': ['a11yr', 19:13:56 INFO - 'ts_paint', 19:13:56 INFO - 'tpaint', 19:13:56 INFO - 'sessionrestore', 19:13:56 INFO - 'sessionrestore_no_auto_restore', 19:13:56 INFO - 'tabpaint']}, 19:13:56 INFO - 'other-e10s': {'talos_options': ['--e10s'], 19:13:57 INFO - 'tests': ['a11yr', 19:13:57 INFO - 'ts_paint', 19:13:57 INFO - 'tpaint', 19:13:57 INFO - 'sessionrestore', 19:13:57 INFO - 'sessionrestore_no_auto_restore', 19:13:57 INFO - 'tabpaint']}, 19:13:57 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 19:13:57 INFO - 'tests': ['a11yr', 19:13:57 INFO - 'ts_paint', 19:13:57 INFO - 'tpaint', 19:13:57 INFO - 'sessionrestore', 19:13:57 INFO - 'sessionrestore_no_auto_restore', 19:13:57 INFO - 'tabpaint']}, 19:13:57 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 19:13:57 INFO - 'tests': ['a11yr', 19:13:57 INFO - 'ts_paint', 19:13:57 INFO - 'tpaint', 19:13:57 INFO - 'sessionrestore', 19:13:57 INFO - 'sessionrestore_no_auto_restore', 19:13:57 INFO - 'tabpaint']}, 19:13:57 INFO - 'other_l64': {'tests': ['a11yr', 19:13:57 INFO - 'ts_paint', 19:13:57 INFO - 'tpaint', 19:13:57 INFO - 'sessionrestore', 19:13:57 INFO - 'sessionrestore_no_auto_restore', 19:13:57 INFO - 'tabpaint']}, 19:13:57 INFO - 'other_nol64': {'tests': ['a11yr', 19:13:57 INFO - 'ts_paint', 19:13:57 INFO - 'tpaint', 19:13:57 INFO - 'sessionrestore', 19:13:57 INFO - 'sessionrestore_no_auto_restore', 19:13:57 INFO - 'tabpaint']}, 19:13:57 INFO - 'svgr': {'tests': ['tsvgx', 19:13:57 INFO - 'tsvgr_opacity', 19:13:57 INFO - 'tart', 19:13:57 INFO - 'tscrollx', 19:13:57 INFO - 'cart']}, 19:13:57 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 19:13:57 INFO - 'tests': ['tsvgx', 19:13:57 INFO - 'tsvgr_opacity', 19:13:57 INFO - 'tart', 19:13:57 INFO - 'tscrollx', 19:13:57 INFO - 'cart']}, 19:13:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:13:57 INFO - 'tests': ['tp5o']}, 19:13:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:13:57 INFO - 'talos_options': ['--e10s'], 19:13:57 INFO - 'tests': ['tp5o']}, 19:13:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:13:57 INFO - 'talos_options': ['--xperf_path', 19:13:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:13:57 INFO - 'tests': ['tp5n']}, 19:13:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:13:57 INFO - 'talos_options': ['--e10s', 19:13:57 INFO - '--xperf_path', 19:13:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:13:57 INFO - 'tests': ['tp5n']}}, 19:13:57 INFO - 'talos.zip': {'path': '', 19:13:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:13:57 INFO - Running post-action listener: _resource_record_post_action 19:13:57 INFO - ##### 19:13:57 INFO - ##### Running create-virtualenv step. 19:13:57 INFO - ##### 19:13:57 INFO - Running pre-action listener: _resource_record_pre_action 19:13:57 INFO - Running main action method: create_virtualenv 19:13:57 INFO - Creating virtualenv /builds/slave/test/build/venv 19:13:57 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 19:13:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:13:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:13:57 INFO - Using real prefix '/tools/python27' 19:13:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:13:57 INFO - Installing distribute.............................................................................................................................................................................................done. 19:14:00 INFO - Installing pip.................done. 19:14:00 INFO - Return code: 0 19:14:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:00 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 0x10cc00a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39bca48e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, '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.xwxsBGSoXk/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.JafRscLBbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:14:00 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 19:14:00 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 19:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:00 INFO - 'HOME': '/Users/cltbld', 19:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:00 INFO - 'LOGNAME': 'cltbld', 19:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:00 INFO - 'MOZ_NO_REMOTE': '1', 19:14:00 INFO - 'NO_EM_RESTART': '1', 19:14:00 INFO - 'PAGER': '/bin/cat', 19:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:00 INFO - 'PWD': '/builds/slave/test', 19:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:00 INFO - 'SHELL': '/bin/bash', 19:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:14:00 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:00 INFO - 'USER': 'cltbld', 19:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:00 INFO - 'XPC_FLAGS': '0x0', 19:14:00 INFO - 'XPC_SERVICE_NAME': '0', 19:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:00 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 19:14:00 INFO - Cleaning up... 19:14:00 INFO - Return code: 0 19:14:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:00 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 0x10cc00a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39bca48e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, '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.xwxsBGSoXk/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.JafRscLBbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:14:00 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 19:14:00 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 19:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:00 INFO - 'HOME': '/Users/cltbld', 19:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:00 INFO - 'LOGNAME': 'cltbld', 19:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:00 INFO - 'MOZ_NO_REMOTE': '1', 19:14:00 INFO - 'NO_EM_RESTART': '1', 19:14:00 INFO - 'PAGER': '/bin/cat', 19:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:00 INFO - 'PWD': '/builds/slave/test', 19:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:00 INFO - 'SHELL': '/bin/bash', 19:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:14:00 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:00 INFO - 'USER': 'cltbld', 19:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:00 INFO - 'XPC_FLAGS': '0x0', 19:14:00 INFO - 'XPC_SERVICE_NAME': '0', 19:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:01 INFO - Downloading/unpacking psutil>=0.7.1 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:14:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:04 INFO - Installing collected packages: psutil 19:14:04 INFO - Running setup.py install for psutil 19:14:04 INFO - building 'psutil._psutil_osx' extension 19:14:04 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 19:14:04 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 19:14:04 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 19:14:04 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 19:14:05 INFO - building 'psutil._psutil_posix' extension 19:14:05 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 19:14:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:14:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:14:05 INFO - ^ 19:14:05 INFO - 1 warning generated. 19:14:05 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 19:14:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:14:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:14:05 INFO - Successfully installed psutil 19:14:05 INFO - Cleaning up... 19:14:05 INFO - Return code: 0 19:14:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:14:05 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')]} 19:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:05 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')]} 19:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:05 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 0x10cc00a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39bca48e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, '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.xwxsBGSoXk/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.JafRscLBbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:14:05 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 19:14:05 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 19:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:05 INFO - 'HOME': '/Users/cltbld', 19:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:05 INFO - 'LOGNAME': 'cltbld', 19:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:05 INFO - 'MOZ_NO_REMOTE': '1', 19:14:05 INFO - 'NO_EM_RESTART': '1', 19:14:05 INFO - 'PAGER': '/bin/cat', 19:14:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:05 INFO - 'PWD': '/builds/slave/test', 19:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:05 INFO - 'SHELL': '/bin/bash', 19:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:14:05 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:05 INFO - 'USER': 'cltbld', 19:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:05 INFO - 'XPC_FLAGS': '0x0', 19:14:05 INFO - 'XPC_SERVICE_NAME': '0', 19:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:14:05 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 19:14:05 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 19:14:05 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 19:14:05 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 19:14:05 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 19:14:05 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 19:14:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:14:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:14:08 INFO - Installing collected packages: mozsystemmonitor 19:14:08 INFO - Running setup.py install for mozsystemmonitor 19:14:08 INFO - Successfully installed mozsystemmonitor 19:14:08 INFO - Cleaning up... 19:14:08 INFO - Return code: 0 19:14:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cc00a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39bca48e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, '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.xwxsBGSoXk/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.JafRscLBbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:14:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:14:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:14:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:08 INFO - 'HOME': '/Users/cltbld', 19:14:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:08 INFO - 'LOGNAME': 'cltbld', 19:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:08 INFO - 'MOZ_NO_REMOTE': '1', 19:14:08 INFO - 'NO_EM_RESTART': '1', 19:14:08 INFO - 'PAGER': '/bin/cat', 19:14:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:08 INFO - 'PWD': '/builds/slave/test', 19:14:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:08 INFO - 'SHELL': '/bin/bash', 19:14:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:14:08 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:14:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:08 INFO - 'USER': 'cltbld', 19:14:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:08 INFO - 'XPC_FLAGS': '0x0', 19:14:08 INFO - 'XPC_SERVICE_NAME': '0', 19:14:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:08 INFO - Downloading/unpacking blobuploader==1.2.4 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14:10 INFO - Downloading blobuploader-1.2.4.tar.gz 19:14:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:14:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:14:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:12 INFO - Downloading docopt-0.6.1.tar.gz 19:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:14:12 INFO - Installing collected packages: blobuploader, requests, docopt 19:14:12 INFO - Running setup.py install for blobuploader 19:14:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:14:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:14:12 INFO - Running setup.py install for requests 19:14:12 INFO - Running setup.py install for docopt 19:14:12 INFO - Successfully installed blobuploader requests docopt 19:14:12 INFO - Cleaning up... 19:14:13 INFO - Return code: 0 19:14:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14: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')]} 19:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14: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')]} 19:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:13 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 0x10cc00a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39bca48e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, '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.xwxsBGSoXk/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.JafRscLBbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:14:13 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 19:14:13 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 19:14:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:14:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:13 INFO - 'HOME': '/Users/cltbld', 19:14:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:13 INFO - 'LOGNAME': 'cltbld', 19:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:13 INFO - 'MOZ_NO_REMOTE': '1', 19:14:13 INFO - 'NO_EM_RESTART': '1', 19:14:13 INFO - 'PAGER': '/bin/cat', 19:14:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:13 INFO - 'PWD': '/builds/slave/test', 19:14:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:13 INFO - 'SHELL': '/bin/bash', 19:14:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:14:13 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:14:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:13 INFO - 'USER': 'cltbld', 19:14:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:13 INFO - 'XPC_FLAGS': '0x0', 19:14:13 INFO - 'XPC_SERVICE_NAME': '0', 19:14:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:13 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-jT8ht9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:13 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-2vpFtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:13 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-cDiXBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:13 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-1lgONb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:13 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-TeRVDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:13 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-ZlYROV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:13 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-YmFWyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-20f8Si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-uEAjfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-cpNESJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-1BSKjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-aGrVUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-69eSSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-FdeS7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-whn3Dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-S8Y8hP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:14:14 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-s4NdA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:14:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:14:15 INFO - Running setup.py install for manifestparser 19:14:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:14:15 INFO - Running setup.py install for mozcrash 19:14:15 INFO - Running setup.py install for mozdebug 19:14:15 INFO - Running setup.py install for mozdevice 19:14:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:14:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:14:15 INFO - Running setup.py install for mozfile 19:14:15 INFO - Running setup.py install for mozhttpd 19:14:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:14:15 INFO - Running setup.py install for mozinfo 19:14:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Running setup.py install for mozInstall 19:14:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Running setup.py install for mozleak 19:14:16 INFO - Running setup.py install for mozlog 19:14:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Running setup.py install for moznetwork 19:14:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Running setup.py install for mozprocess 19:14:16 INFO - Running setup.py install for mozprofile 19:14:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:14:16 INFO - Running setup.py install for mozrunner 19:14:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:14:17 INFO - Running setup.py install for mozscreenshot 19:14:17 INFO - Running setup.py install for moztest 19:14:17 INFO - Running setup.py install for mozversion 19:14:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:14:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:14:17 INFO - Cleaning up... 19:14:17 INFO - Return code: 0 19:14:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14:17 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')]} 19:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:17 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')]} 19:14:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:17 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 0x10cc00a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39bca48e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, '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.xwxsBGSoXk/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.JafRscLBbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:14:17 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 19:14:17 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 19:14:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:14:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:17 INFO - 'HOME': '/Users/cltbld', 19:14:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:17 INFO - 'LOGNAME': 'cltbld', 19:14:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:17 INFO - 'MOZ_NO_REMOTE': '1', 19:14:17 INFO - 'NO_EM_RESTART': '1', 19:14:17 INFO - 'PAGER': '/bin/cat', 19:14:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:17 INFO - 'PWD': '/builds/slave/test', 19:14:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:17 INFO - 'SHELL': '/bin/bash', 19:14:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:14:17 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:14:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:17 INFO - 'USER': 'cltbld', 19:14:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:17 INFO - 'XPC_FLAGS': '0x0', 19:14:17 INFO - 'XPC_SERVICE_NAME': '0', 19:14:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:14:17 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-qx8Snq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:14:17 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)) 19:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:14:17 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-0LecU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:14:18 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)) 19:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:14:18 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-EAdSuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:14:18 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)) 19:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:14:18 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-boifvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:14:18 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)) 19:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:14:18 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-i6RPAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:14:18 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)) 19:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:14:18 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-PQb3V0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:14:18 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)) 19:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:14:18 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-_Lgo18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:14:18 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)) 19:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:14:18 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-K5UE25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:14:18 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)) 19:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:14:18 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-NJbE9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:14:18 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)) 19:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:14:18 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-LaKhL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:14:18 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)) 19:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:14:18 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-fUVK6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:14:19 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)) 19:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:14:19 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-eP9J0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:14:19 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)) 19:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:14:19 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-Lbdn6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:14:19 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)) 19:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:14:19 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-KslzRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:14:19 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)) 19:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:19 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-MEkRU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:14:19 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)) 19:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:14:19 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-xebwnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:14:19 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)) 19:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:14:19 INFO - Running setup.py (path:/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/pip-9hkQSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:14:19 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)) 19:14:19 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)) 19:14:19 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)) 19:14:19 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)) 19:14:19 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)) 19:14:19 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)) 19:14:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14:22 INFO - Downloading blessings-1.6.tar.gz 19:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:14:22 INFO - Installing collected packages: blessings 19:14:22 INFO - Running setup.py install for blessings 19:14:22 INFO - Successfully installed blessings 19:14:22 INFO - Cleaning up... 19:14:22 INFO - Return code: 0 19:14:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:14:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:14:22 INFO - Reading from file tmpfile_stdout 19:14:22 INFO - Current package versions: 19:14:22 INFO - blessings == 1.6 19:14:22 INFO - blobuploader == 1.2.4 19:14:22 INFO - docopt == 0.6.1 19:14:22 INFO - manifestparser == 1.1 19:14:22 INFO - mozInstall == 1.12 19:14:22 INFO - mozcrash == 0.17 19:14:22 INFO - mozdebug == 0.1 19:14:22 INFO - mozdevice == 0.48 19:14:22 INFO - mozfile == 1.2 19:14:22 INFO - mozhttpd == 0.7 19:14:22 INFO - mozinfo == 0.9 19:14:22 INFO - mozleak == 0.1 19:14:22 INFO - mozlog == 3.1 19:14:22 INFO - moznetwork == 0.27 19:14:22 INFO - mozprocess == 0.22 19:14:22 INFO - mozprofile == 0.28 19:14:22 INFO - mozrunner == 6.11 19:14:22 INFO - mozscreenshot == 0.1 19:14:22 INFO - mozsystemmonitor == 0.0 19:14:22 INFO - moztest == 0.7 19:14:22 INFO - mozversion == 1.4 19:14:22 INFO - psutil == 3.1.1 19:14:22 INFO - requests == 1.2.3 19:14:22 INFO - wsgiref == 0.1.2 19:14:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:14: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')]} 19:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14: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')]} 19:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:22 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 0x10cc00a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39bca48e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, '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.xwxsBGSoXk/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.JafRscLBbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:14:22 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 19:14:22 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 19:14:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:14:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:22 INFO - 'HOME': '/Users/cltbld', 19:14:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:22 INFO - 'LOGNAME': 'cltbld', 19:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:22 INFO - 'MOZ_NO_REMOTE': '1', 19:14:22 INFO - 'NO_EM_RESTART': '1', 19:14:22 INFO - 'PAGER': '/bin/cat', 19:14:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:22 INFO - 'PWD': '/builds/slave/test', 19:14:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:22 INFO - 'SHELL': '/bin/bash', 19:14:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:14:22 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:14:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:22 INFO - 'USER': 'cltbld', 19:14:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:22 INFO - 'XPC_FLAGS': '0x0', 19:14:22 INFO - 'XPC_SERVICE_NAME': '0', 19:14:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:22 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)) 19:14:22 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)) 19:14:22 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)) 19:14:22 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)) 19:14:22 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)) 19:14:22 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)) 19:14:22 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)) 19:14:22 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)) 19:14:22 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)) 19:14:22 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)) 19:14:22 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)) 19:14:22 INFO - Cleaning up... 19:14:22 INFO - Return code: 0 19:14:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:14: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')]} 19:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14: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')]} 19:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cc00a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39bca48e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, '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.xwxsBGSoXk/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.JafRscLBbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:14: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', 'jsonschema'] in /builds/slave/test/build 19:14: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 jsonschema 19:14:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:14:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:22 INFO - 'HOME': '/Users/cltbld', 19:14:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:22 INFO - 'LOGNAME': 'cltbld', 19:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:22 INFO - 'MOZ_NO_REMOTE': '1', 19:14:22 INFO - 'NO_EM_RESTART': '1', 19:14:22 INFO - 'PAGER': '/bin/cat', 19:14:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:22 INFO - 'PWD': '/builds/slave/test', 19:14:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:22 INFO - 'SHELL': '/bin/bash', 19:14:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:14:22 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:14:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:22 INFO - 'USER': 'cltbld', 19:14:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:22 INFO - 'XPC_FLAGS': '0x0', 19:14:22 INFO - 'XPC_SERVICE_NAME': '0', 19:14:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:23 INFO - Downloading/unpacking jsonschema 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:14:25 INFO - Downloading/unpacking functools32 (from jsonschema) 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14:25 INFO - Downloading functools32-3.2.3-2.tar.gz 19:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:14:26 INFO - warning: no files found matching '*.txt' 19:14:26 INFO - no previously-included directories found matching 'build' 19:14:26 INFO - no previously-included directories found matching 'dist' 19:14:26 INFO - no previously-included directories found matching '.git*' 19:14:26 INFO - Installing collected packages: jsonschema, functools32 19:14:26 INFO - Running setup.py install for functools32 19:14:26 INFO - warning: no files found matching '*.txt' 19:14:26 INFO - no previously-included directories found matching 'build' 19:14:26 INFO - no previously-included directories found matching 'dist' 19:14:26 INFO - no previously-included directories found matching '.git*' 19:14:26 INFO - Successfully installed jsonschema functools32 19:14:26 INFO - Cleaning up... 19:14:26 INFO - Return code: 0 19:14:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:14:26 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 0x10cc00a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc084c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa39bca48e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, '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.xwxsBGSoXk/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.JafRscLBbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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 19:14:26 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 19:14:26 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 19:14:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:14:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:26 INFO - 'HOME': '/Users/cltbld', 19:14:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:26 INFO - 'LOGNAME': 'cltbld', 19:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:26 INFO - 'MOZ_NO_REMOTE': '1', 19:14:26 INFO - 'NO_EM_RESTART': '1', 19:14:26 INFO - 'PAGER': '/bin/cat', 19:14:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:26 INFO - 'PWD': '/builds/slave/test', 19:14:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:26 INFO - 'SHELL': '/bin/bash', 19:14:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:14:26 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:14:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:26 INFO - 'USER': 'cltbld', 19:14:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:26 INFO - 'XPC_FLAGS': '0x0', 19:14:26 INFO - 'XPC_SERVICE_NAME': '0', 19:14:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:14:26 INFO - Downloading/unpacking flake8 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14: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 19:14:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:14:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 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 19:14:29 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 19:14:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:14:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:14:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:14:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:14:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:14:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:14:30 INFO - no previously-included directories found matching 'docs/_build' 19:14:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:14:31 INFO - Running setup.py install for pep8 19:14:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:14:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:14:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:14:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:14:31 INFO - no previously-included directories found matching 'docs/_build' 19:14:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:14:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:14:31 INFO - Cleaning up... 19:14:31 INFO - Return code: 0 19:14:31 INFO - Running post-action listener: _resource_record_post_action 19:14:31 INFO - Running post-action listener: _start_resource_monitoring 19:14:31 INFO - Starting resource monitoring. 19:14:31 INFO - ##### 19:14:31 INFO - ##### Running install step. 19:14:31 INFO - ##### 19:14:31 INFO - Running pre-action listener: _resource_record_pre_action 19:14:31 INFO - Running main action method: install 19:14:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:14:31 INFO - Reading from file tmpfile_stdout 19:14:31 INFO - Detecting whether we're running mozinstall >=1.0... 19:14:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:14:31 INFO - Reading from file tmpfile_stdout 19:14:31 INFO - Output received: 19:14:31 INFO - Usage: mozinstall [options] installer 19:14:31 INFO - Options: 19:14:31 INFO - -h, --help show this help message and exit 19:14:31 INFO - -d DEST, --destination=DEST 19:14:31 INFO - Directory to install application into. [default: 19:14:31 INFO - "/builds/slave/test"] 19:14:31 INFO - --app=APP Application being installed. [default: firefox] 19:14:31 INFO - mkdir: /builds/slave/test/build/application 19:14:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:15:06 INFO - Reading from file tmpfile_stdout 19:15:06 INFO - Output received: 19:15:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:15:06 INFO - Running post-action listener: _resource_record_post_action 19:15:06 INFO - ##### 19:15:06 INFO - ##### Running run-tests step. 19:15:06 INFO - ##### 19:15:06 INFO - Running pre-action listener: _resource_record_pre_action 19:15:06 INFO - Running main action method: run_tests 19:15:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:15:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:15:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:15:06 INFO - 2016-03-15 19:15:06.303 screenresolution[1645:7653] starting screenresolution argv=screenresolution get 19:15:06 INFO - 2016-03-15 19:15:06.306 screenresolution[1645:7653] Display 0: 1600x1200x32@60 19:15:06 INFO - 2016-03-15 19:15:06.311 screenresolution[1646:7655] starting screenresolution argv=screenresolution list 19:15:06 INFO - Available Modes on Display 0 19:15:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:15:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:15:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:15:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:15:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:15:06 INFO - Intel Iris: 19:15:06 INFO - Chipset Model: Intel Iris 19:15:06 INFO - Type: GPU 19:15:06 INFO - Bus: Built-In 19:15:06 INFO - VRAM (Dynamic, Max): 1536 MB 19:15:06 INFO - Vendor: Intel (0x8086) 19:15:06 INFO - Device ID: 0x0a2e 19:15:06 INFO - Revision ID: 0x0009 19:15:06 INFO - Displays: 19:15:06 INFO - Display: 19:15:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:15:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:15:06 INFO - Display Serial Number: 1600x1200 60 19:15:06 INFO - Main Display: Yes 19:15:06 INFO - Mirror: Off 19:15:06 INFO - Online: Yes 19:15:06 INFO - Rotation: Supported 19:15:06 INFO - Return code: 0 19:15:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:15:06 INFO - Python 2.7.3 19:15:06 INFO - Return code: 0 19:15:06 INFO - grabbing minidump binary from tooltool 19:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:15:06 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 0x10caa4d30>, '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 0x10c8c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:15:06 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 19:15:06 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 19:15:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:15:06 INFO - Return code: 0 19:15:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:15:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:15:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:15:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:15:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:15:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:15:07 INFO - Return code: 0 19:15:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0049', '--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 19:15:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0049 --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 19:15:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xwxsBGSoXk/Render', 19:15:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:07 INFO - 'HOME': '/Users/cltbld', 19:15:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:07 INFO - 'LOGNAME': 'cltbld', 19:15:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:15:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:07 INFO - 'MOZ_NO_REMOTE': '1', 19:15:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:15:07 INFO - 'NO_EM_RESTART': '1', 19:15:07 INFO - 'PAGER': '/bin/cat', 19:15:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:07 INFO - 'PWD': '/builds/slave/test', 19:15:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:15:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:07 INFO - 'SHELL': '/bin/bash', 19:15:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JafRscLBbs/Listeners', 19:15:07 INFO - 'TMPDIR': '/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/', 19:15:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:07 INFO - 'USER': 'cltbld', 19:15:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:07 INFO - 'XPC_FLAGS': '0x0', 19:15:07 INFO - 'XPC_SERVICE_NAME': '0', 19:15:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0049', '--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 19:15:08 INFO - mozversion application_buildid: 20160315170242 19:15:08 INFO - mozversion application_changeset: 55e4902a0ea4abe5f7d50a17a3b540b378b344ba 19:15:08 INFO - mozversion application_display_name: Nightly 19:15:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:15:08 INFO - mozversion application_name: Firefox 19:15:08 INFO - mozversion application_remotingname: firefox 19:15:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:15:08 INFO - mozversion application_vendor: Mozilla 19:15:08 INFO - mozversion application_version: 48.0a1 19:15:08 INFO - mozversion platform_buildid: 20160315170242 19:15:08 INFO - mozversion platform_changeset: 55e4902a0ea4abe5f7d50a17a3b540b378b344ba 19:15:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:15:08 INFO - mozversion platform_version: 48.0a1 19:15:08 INFO - using testdate: 1458094508 19:15:08 INFO - actual date: 1458094508 19:15:08 INFO - starting webserver on 'localhost:49274' 19:15:08 INFO - SUITE-START | Running 2 tests 19:15:08 INFO - TEST-START | dromaeo_css 19:15:08 INFO - operating with platform_type : mac_ 19:15:08 INFO - Initialising browser for dromaeo_css test... 19:15:08 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/tmpVmqRB5/profile http://localhost:49274/getInfo.html) 19:15:10 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 19:15:10 INFO - PROCESS | 1664 | colorDepth:24 19:15:10 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 19:15:10 INFO - PROCESS | 1664 | __metrics 19:15:10 INFO - PROCESS | 1664 | 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" 19:15:10 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:15:11 INFO - TEST-INFO | 1664: exit 0 19:15:11 INFO - Browser initialized. 19:15:11 INFO - Running cycle 1/1 for dromaeo_css test... 19:15:11 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/tmpVmqRB5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:15:23 INFO - PROCESS | 1667 | RSS: Main: 242868224 19:15:23 INFO - PROCESS | 1667 | 19:16:14 INFO - PROCESS | 1667 | 1458094574163 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] 19:18:24 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 19:18:26 INFO - PROCESS | 1667 | RSS: Main: 377507840 19:18:26 INFO - PROCESS | 1667 | 19:21:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 19:21:29 INFO - PROCESS | 1667 | RSS: Main: 368009216 19:21:29 INFO - PROCESS | 1667 | 19:24:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 19:24:32 INFO - PROCESS | 1667 | RSS: Main: 374919168 19:24:32 INFO - PROCESS | 1667 | 19:27:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 19:27:34 INFO - PROCESS | 1667 | RSS: Main: 333504512 19:27:34 INFO - PROCESS | 1667 | 19:30:37 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 19:30:38 INFO - PROCESS | 1667 | RSS: Main: 333725696 19:30:38 INFO - PROCESS | 1667 | 19:33:41 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 19:33:41 INFO - PROCESS | 1667 | RSS: Main: 404029440 19:33:41 INFO - PROCESS | 1667 | 19:33:41 INFO - PROCESS | 1667 | __start_tp_report 19:33:41 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 19:33:41 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 19:33:41 INFO - PROCESS | 1667 | |i|pagename|runs| 19:33:41 INFO - PROCESS | 1667 | |0;dojo.html;3014.8944786932457;3489.842957066933;3533.1520435095586;3541.1678255609927;3545.096000519867;5320.334958130234;5546.805861794838;5609.971950140254;5636.7181640918;5649.689267090308;5621.943780562193;5645.689487078219;5660.29246344207;5780.0751879699255;5794.942050579493;5073.873153171164;5094.1594636884865;5120.334356533645;5126.871828204287;5235.293235413209;5196.220566914974;5216.295800067;5269.130593452076;5280.815171468999;5316.654417462872;5071.923921141186;5077.339945807058;5132.461091585367;5165.4834516548235;5216.95660867828;5181.766820493087;5192.636515443921;5203.505666961632;5214.878801057791;5240.816571419982;5116.795328186868;5144.0740666679985;5172.9741351293005;5174.24973378858;5186.403563590197;5453.918191227135;5544.0575102232715;5574.637648552872;5579.6652200868075;5580.832575022756;6089.420592079687;6095.05526643371;6104.938950610482;6111.847203819895;6163.537734669918;5143.717095559743;6164.722587483574;6172.35190305016;6192.969035154841;6202.472789812871;5692.1461780733225;5761.740721667535;5782.721292281426;5789.768409263583;5820.941790582082;6094.481969032593;6145.740123274718;6246.46905013079;6252.155958945484;6253;6060.818175454743;6096.055111457731;6107.755689772448;6109.450149486567;6116.174316467309;5180;6082.695865206722;6083.209182806296;6166.475849552839;6175.938240617536;6076.270847498328;6104.603200791934;6119.685352982247;6148.600340977822;6148.78479253224;6123.120160253514;6133.55976224962;6134.19168101403;6142.539309551801;6147.784827531015;4744.146053710297;4814.181589129857;4816.15717249487;4829.517048295213;4862.465128835825;4319.092990471973;4360.302351623725;4374.868753937387;4390.187815254124;4431.290993441034;5718.628289161191;5753.165790960287;5759.1937128802;5762.250907382014;5764.221830052889;6121.112438696453;6244.594101383395;6258.624482531062;6259.186305780219;6259.843503912439;5601.243832082617;5691.800786972519;5693.971530142323;5699.715014249271;5700.7719691212715;5285.101532739443;5295.046891559479;5299.920501192485;5303.469653034743;5307.761150748225;6225.844353891189;6251.906221406683;6308.936910630835;6319.873602528016;6326.430621244018;6203.7828675996125;6221.564490485623;6265.843353916189;6275.560710750204;6277.497800176066;5078.111330517218;6293.527985401021;6329.746810127727;6336.20797400325;6338.651374174465;5892.734826932884;6078.878422431615;6128.63228206309;6142.867137759864;6154.507639388748;5136.691798492103;5167.58659307247;6197.504199664107;6218.626882387071;6230.43926046658;6050;6065.999110146777;6066.4540191382985;6068.393160683896;6069.2110025696375;5931.991561434653;6020.4280593343565;6030.185924900258;6065.60573562717;6109.633421994695;5894.557908156819;5898.469137777621;5914.67469289176;5915.526757859447;5949.91075133882;6070.0591408331775;6075.756969721162;6096.568695548639;6098.359672234529;6107.9389206109145;4915.43472500667;5775.018246897954;5807.070868660994;5898.616589921641;5908.793192238423 19:33:41 INFO - PROCESS | 1667 | |1;ext.html;460.7972492103476;562.9155626656;580.8373655376494;595.3957985095835;642.0273285971753;26825;38302.80848595757;38326.616733832685;38534;38538.61461385385;41374.58625413745;41788;41872;41901.79049104758;41941.58058419415;9536.332456728032;9723.416595004323;9734.26992975526;9741.659041933533;9745.269104817167;9457.385269957465;9582.664606738765;9614.278929080312;9619.615215391412;9764.707058788248;9704.029597040275;9756.609735610567;9775.120239178472;9790.510474476283;9792.461414622192;9702.70891873245;9780.755481112958;9781.755456113582;9786.706398808046;9820.41077535343;9069.31980101495;9199.724008279762;9216.447013179231;9216.49309287989;9220.492872891988;40185.59814401848;40485;40657;40657;40661.390079148834;3168.429682657121;3622.6377362263825;3851.614838516154;3917.4515567820526;3993.201359728066;28407;29146.854265728747;29942.85028574843;30042.849785750932;30082;2711.701712811589;2777.6805667348312;2802.607634931111;2846.1746093632823;2859.9428011439863;499483;504780;506260;506394;506700;63329.68335158295;63354.36645633577;63852.68073659602;63896.041559376645;63925;65896;66073.33926660768;66114;66175;66241.34396640027;17129.571760706083;17285.56786080358;17325.56686082858;17342.739858902125;17353.56616084608;13284.269365185008;13304.201747895157;13329.400176992058;13329.66675833112;13342.332883355793;18799.53691334935;19352.903235483733;19611.803881961285;19656.606867862563;19695.11371988264;7612.961935190399;8821.338399620054;8896.911030889738;8903.421277617064;9234.722958311262;941.8210539997378;1002.7142264454595;1023.3348323589727;1066.983995240072;1069.6790962711154;27566.44867102647;27657.308567285978;28084;28662.713372866423;28830.279243018675;1068.4711068021224;1107.1585594947899;1119.322809700136;1145.118258940611;1241.7951038078616;14609.342579583945;15175.013624114397;15284.617884552752;15580;15724.68550628981;536277;547628;549321.2533937305;549614;549737;58552.12171817341;59407.702961484916;59432.405675943555;59544.106838397456;59631.70184149052;1340.8659134086513;1360.7074478987065;1361.0200655528004;1490.3070072416378;1552.3169805285638;23931.52136957216;28358.43283134367;29025.274368140967;29762.702372975993;30015.849920750257;54300;54441.72779136079;54693.72653136709;54809.45190548043;54839.72580137074;497136;506109;510387;511236;511851;27357;31577.842110789297;31581.684183157875;32040.198995025312;32048.878289259763;38435;39054.80472597619;39287.41068883912;39492.60507395004;39947.60052399555;33643.831780841916;35175.47236791501;35615.287694246486;35637;36036.27927441489;34037;34450.65549344474;34486.13784655404;35143;35224.94325170249;34219.82890085533;34839.47740783941;35180.29639407249;35401.822990885914;35496.46755298621;30388.696113038586;30546.236344091576;30744;30746.846265768527;30751;20403.4899127523;21403.73880048493;23132.83608950405;23571.41071473227;23789 19:33:41 INFO - PROCESS | 1667 | |2;jquery.html;361.8751530721901;366.5967435820596;368.93174762668906;369.70423661071135;376.7475791219882;16003;19282.32511862085;19775.011249437506;19792.604147917034;19806.504837379056;19420.417387478363;19692.50768730779;20866;20923;20928.68606970893;5168.121419358699;5315.893682126355;5348.812791552296;5357.464253574651;5357.517823395893;3935.724499285051;4115.814788334517;5270.183121616155;7197.172325182619;7523.307255867413;7384.704611815548;7527.47307688462;7530.472866899319;7538.359239464652;7549.735759248428;7328.30381113796;7452.925470745277;7551.282628150317;7560.3573696235335;7570.394368450538;4355.891102722426;4379.233634114049;6856.760013399507;7078.610676412795;7180.102487189101;25951.480970380675;26100;27805.8877644896;29219.269518262;29371.265718356997;2403.3030421177837;2422.9636555451684;2443.5601591713485;2703.5133675938323;3008.7893847430687;15330.310136043905;15392.230388480642;15919.442819501377;16337.673246535003;16374.508764737076;1638.7541868719788;1705.377537198926;1933.5456167800555;1964.1848632817364;2034.216826521785;104078;106943.99999999923;107165.92834071637;108219.45890270577;109931.45034274858;18741;19299;19591.510212244808;19595.31416400419;19900.402987910096;29086.272843178667;29230.26924326909;29286.707132928823;29300.853495732386;29303;10647.254692171473;11018.559257629766;11032.944835275934;11060.391678457601;11147.940945610155;9361.229658929247;9498.287628427895;9521.90478095224;9583.904160958302;9624.951875240578;8829.823403532022;8875.423097498771;8957.641694332153;8958.328125390623;8988.865167022499;2971.682087971386;3177.4280629486916;3411.5053317269353;3467.601225859008;3512.2448673535314;792.6683172270641;940.3793496292415;948.2650945517279;1009.4548943570536;1047.1831971062584;19252.518687032654;19851.60296794085;19852.00739963025;19933.704309219855;19941.401757947286;928.9860652090224;929.7582628516499;947.6256878533079;1008.1027885182194;1045.811753884293;10109.64616238428;10609.9469502652;10619.36283822973;10623.415712135908;10636.202284828669;628806;631912;641548;653772;654754;107238;108659;108782.45608771905;110080;111771.44114279376;1002.0280328081749;1014.3001329083056;1032.8244198486275;1053.6628278950661;1054.1566746602687;14471.204083775494;17170.141492925555;17333;17535.035573043606;17624.471265862045;49278.75360623317;49425;50104.749476252386;50597.49402505928;52230.1273429965;68127;69058.65470672614;69436.65281673559;71572;71703.20741981284;7501.099868015873;7654.196309387657;7768.262015108556;7774.611269436618;7832.74676051829;9278.793756811572;11643.941780291045;11821.172517923662;12126.333051682243;12144.210626309261;8853.80473636051;9568.234541236823;10384.532696028546;10578.947105264733;10613;10261.384316941007;10331;10355.896441035493;10488.790224195322;10617.946910265398;10111.443870587189;10272.33229840088;10496.265261431932;10859.945700271448;11207.887921121011;7243.782686519413;7707.703740401288;7821;7907.920920790719;7950.64222110018;10433.32183408076;10755.623553175601;10960.068394186583;11021.173411994292;11087.22389432732 19:33:41 INFO - PROCESS | 1667 | |3;mootools.html;1412.6750847305113;1588.555204542728;1626.2031604513788;1908.5037890148558;2028.9391318260448;7799.727009554666;8445.61994710238;8591.269742071916;8602.784930376736;8643.783905402352;8341.374396920224;8582.313414926806;8596.398252122339;8596.785080372994;8613.483191008543;2148.79586439288;2383.6067048936907;2444.62108373202;2472.9752702473015;2486.1298545509107;2468.790152837011;2475.8762061896923;2476.4923190745853;2479.47930934504;2479.776820086192;2389.7499400814877;2420.9031638734427;2424.309071914505;2457.0908763757348;2461.323136137559;2415.7946574541184;2441.645961335605;2453.423445490317;2462.9014839406404;2474.7401522840023;2447.7674620910984;2453.0065323543995;2455.091616102036;2474.6288056791445;2475.5048990201853;8676.696315628986;8747.518886461243;8747.562621868945;8760.386772925898;8766.649334026632;5243.580513558906;5410.161424979135;5417.51242388183;5441.129419292934;5442.700651464168;3544.769589976643;3623.0036739896477;3693.9445908311395;3711.4247291669835;3723.1250656095795;1000.1798525209331;1020.209337763232;1029.25893356783;1029.763154474475;1030.6032177611598;11650.067994560415;11670.591529296518;11673.357965311905;11711.063114950783;11714.53141874324;4729.491552351559;4805.927911081337;4897.363342765418;4904.092742842514;4911.803527858917;9930.2055835533;9936.254780891462;9936.701898943043;9944.850827237597;9963.750906227257;5382.30030096085;5441.809536666197;5444.911017796378;5447.809326673627;5456.890862182734;4364.738115713068;4472.9552704472535;4476.574725401082;4487.506374298824;4949.82675606352;3820.465134881119;3822.3310920588788;3903.8048097595006;3905.062784931596;3913.0804260998643;1109;1254.4668515880896;1291.8449786025737;1296.1445446005591;1303.1269049736509;1335.4991878045732;1337.2177276293282;1368.568900796246;1410.4992727581766;1411.2449839335907;7876.72431464896;8058.033036035713;8061.677532898736;8107.391945603986;8128.983877015373;1185.5909711149638;1199.3763243113706;1205.9397030148457;1212.6422705301957;1227.030645789834;6194.38056194386;6227.626342419469;6283.622982621057;6284.874302513924;6312.147860038928;10136.847947280796;10499.790004199873;10601.681949541526;10619.20355973305;10637;9862.654807081717;10399.272050956512;10468.68593942183;10511.211659125596;10513.053825155772;8038.437309388403;8054.23484768946;8178.5092894426525;8181.854540364354;8189.426740128134;7859.685612575433;7900;7927.484713493604;7969;7972.561509117054;6648.933510664831;9204.907950920406;9254.35219534626;9322.673706420243;9454.527273636428;9178.357514973888;9366.06339366058;9720.562574684014;9737.951310243687;9945.055219754111;6697.899531506934;7388.519746216479;7481.438892083007;7513.774586762406;7560.3573696236435;7723.575203363869;8177.713780017671;8216.46592971455;8220.219079187467;8288.046874609498;6637.203535575762;7077.68150433242;7262.419006479363;7284.963575182091;7435.256474352522;7208.315210055036;7238.8914166286495;7239.167495738057;7351.595662238628;7380.261973802577;7690.077190737147;7693.923060769321;7754;7788.961055194688;7791.987041684545;7000.894986575104;7029.5430796998035;7052.612106334201;7056.858862822817;7057.329553692391;7048.753293634698;7117.857642847218;7140.85718285621;7142.285771423024;7188.4968052235845 19:33:41 INFO - PROCESS | 1667 | |4;prototype.html;330.80482515315964;332.8285932744637;337.37417091295663;339.6773065587691;344.73972151025987;6515.837104072395;6531;6541.607503549791;6546.410823025927;6551.672416379186;5216.9478305217035;6468.579542329745;6471.158749362588;6473.773417930373;6475.676216189184;4635.675502714811;4714.976425117869;4737.147313483571;4843.588294994912;4889.755512224384;4777.28340748887;4805.879853003668;4819.228923372261;4823.686460380081;4848.2727590861305;4441.778510909493;4755.762211889409;4757.286407038954;4776.522347765231;4809.398825146856;3859;4606.838760643395;4682.27424749161;4796.592289655384;4807.062622788558;4123.36087906375;4130;4133.256013917494;4138.710290279681;4139.4204811326435;6150.415710507495;6200.286966998808;6204.441600255954;6207.782727604512;6210.689465526705;5605.971970140164;5619.157126431027;5623.522000629951;5628.521575666074;5653.491185793298;6857.417119544844;6874.931250687529;6886.724531018804;6887.690053947585;6889.345512176335;4312.460942382202;4468.0840427712565;4482.439695038121;4491.124230775;4623.329617205486;7185.317394847481;7191.928080719178;7227.349538541556;7229.349358557755;7248.601326927017;4531.909361812811;4542.341360502709;4544.886377840514;4547.249703798902;4548.340490628906;4601.596513063548;4605.286180642006;4608.308753686973;4615.16926953145;4627.120847039052;4815.95184048155;5177.197534382177;5458.1169496523635;5484.2596249506605;5486.972565137149;6595.109660195908;6888.207856096613;6890.758823441155;6901.930980690229;6923.930760692429;1587.190532828266;1596.106180538902;1610.1627153879917;1613.6772645470953;1614.5559971008067;973.8636590877283;1019.928604997643;1027.4965267019106;1044.2742294105676;1055.672741450153;6439.967800160969;6492.448141907897;6635.933640663531;6638.871391863395;6655.767048153291;7189.712411503481;7211.387032102225;7217.206107328189;7223;7226.699194145104;6593.044008618724;6608.008798680236;6614.86770264592;6631.10480085192;6642.535022548375;3757.0607348162957;3806.0104372863257;3808.5620153682125;3811.5426148862316;3813.4089216170983;7228.891566626505;7545.962270188724;7572.432067594952;7572.697092116254;7586.203448637924;6024.1566180734735;7339.266073392618;7361.963190184015;7378.188399276075;7387.926120738832;7529.698812047457;7541.5475071495885;7562.470627056164;7563.659635316534;7571.772846814604;6899.379055884994;7178.174509931216;7192.460565457507;7194.244604316681;7200.927990720026;7039.859202816017;7073.610951397723;7100.076989991268;7108.822279443055;7119.466040046914;7480.925190748023;7536.1333446654335;7544.6227688616445;7548.5470871747875;7557.433192510473;4563.726176429424;4566.908661826811;4567.703099298535;4578.381918441101;4590.449146102489;6891.896621550581;7181.964090179516;7184.491256835914;7184.676689549087;7192;6929.341712537301;6932.688029038604;6943.409810166192;6943.722251109899;6949.617771022642;6849.794506164823;6909.723611055501;6915.827104322432;6920.515563910478;6927.515073944775;6753.1896172457655;6758.189017317954;6764.053032575345;6768.221654509597;6788.321167883172;6678.699458524281;6897.379235868796;6925.930740692528;6946.596167877805;6948.235694073749;6468.967655161882;6606.933930660824;6611.33886611335;6774.729010839511;6787.93212067873 19:33:41 INFO - PROCESS | 1667 | |5;yui.html;660.6597602234853;678.7217240931219;688.7864761923804;781.0822283816516;821.6918655504186;14911.627209319759;15204.315805788738;15266.618334541628;15267.770983435243;15281;11284.492197851096;14049.367778449969;14061.578152655411;14064.367103480343;14078.155310681366;905.6196397513028;923.6997975657904;926.9768255793625;929.4748467116092;932.6316105138466;739.2348918868971;749.7938067031555;920.0477505781517;920.282179899676;922.6401703335705;965.5461932891529;988.7182153086371;991.3011327014474;992.4343124419084;992.6277645882794;972.5769290358681;978.0268632710428;980.8577756225308;983.8671779309809;985.8767654043245;580.5587753307475;718.2458418660385;734.5923012727934;735.9816004599875;737.0970561062709;15012.699746005128;15014.924925375413;15056.849431505654;15077.54767356981;15134.621634459116;12446.81329780054;12461.376931153405;12491.312977786218;12516.687082822911;12614.81077783834;9665.081817227352;9780.755481112958;9805.26460515457;10014.24893133018;10022.448765317904;3292.4732042873266;3355.5973283205926;3401.9659803402146;3422.4866270059442;3459.41189997701;71220.57558848795;71245;71614.64192679003;71854.2814571858;72455.55088898193;9422.717318480456;9482.241420686329;9497.192738617157;9676.90323096774;9687.515624218902;22270.331890043326;22277;22279.220227291968;22320.553588928455;22343.88828055882;7276.527025743309;7373.631318434057;7386;7483.775486735407;7502.6623801929045;3855.2289542091694;3999;4001.459802926626;4018.8794336169963;4047.9797601012397;2990.8953186638364;3454.4990976308295;3514.578250609943;3519.085037890167;3523.5419395478934;671.4091599392503;676.2392308652766;689.1248114894156;691.0325544238007;691.7509696509253;500.32706010415933;512.423523536022;528.521687872476;529.3515443581582;579.8608333999894;7163;7273.199948005715;7539.73610923626;7717.344025757761;7801.6879324827505;491.36123040047795;545.8144230961412;557.6709741252599;581.3430823169791;598.186466405688;3388.186835159544;3410.2667926395447;3456.637053109387;3502.1069627244888;3567.2152126532123;78836;79147.81278280831;79362;92379.5381023104;93113.0688693118;69877;70028;70559;78602;79465;66853.66573167103;67085.66457167683;67189.66405167943;67282;67949;6586.106009376161;6722.495812813961;6742.258351581224;6876.17485901695;6988.510804243858;44675.32987005263;45264.64206073823;45364.54635453603;50893.49106508888;51016.48983510117;58941;63578.68210659102;69928.60142797013;70143.64928175327;71053.28946710467;8994.820103598022;9002.729918102468;9223.953880230556;9237.538123093953;9688.806223875343;9841.114299713061;10294.176465882561;10338.586456541954;10493.895061049598;11390.37352945596;8834.116588341114;9316;9822.5088745561;9887.456189909624;10063.849042264226;10040.347377420445;10225.028622280872;10255.230857685554;10284.948575257076;10343.431111288952;9792.412455252708;9868.753281168028;10043;10051.798964020825;10054.447005414773;8433.662653494037;8449.788755281168;8558.57207139653;8645.870311945451;8722.433041852259;8460.830783384175;8498.660053597787;8512.531810750468;8586.012608550096;8621.568921553771 19:33:41 INFO - PROCESS | 1667 | __end_tp_report 19:33:41 INFO - PROCESS | 1667 | __start_cc_report 19:33:41 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1257 19:33:41 INFO - PROCESS | 1667 | __end_cc_report 19:33:41 INFO - PROCESS | 1667 | __startTimestamp1458095621339__endTimestamp 19:33:41 INFO - PROCESS | 1667 | 19:33:41 INFO - PROCESS | 1667 | ------- Summary: start ------- 19:33:41 INFO - PROCESS | 1667 | Number of tests: 6 19:33:41 INFO - PROCESS | 1667 | 19:33:41 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5661.89 Median:5820.94 stddev:631.63 (10.9%) stddev-sans-first:598.46 19:33:41 INFO - PROCESS | 1667 | Values: 3014.9 3489.8 3533.2 3541.2 3545.1 5320.3 5546.8 5610.0 5636.7 5649.7 5621.9 5645.7 5660.3 5780.1 5794.9 5073.9 5094.2 5120.3 5126.9 5235.3 5196.2 5216.3 5269.1 5280.8 5316.7 5071.9 5077.3 5132.5 5165.5 5217.0 5181.8 5192.6 5203.5 5214.9 5240.8 5116.8 5144.1 5173.0 5174.2 5186.4 5453.9 5544.1 5574.6 5579.7 5580.8 6089.4 6095.1 6104.9 6111.8 6163.5 5143.7 6164.7 6172.4 6193.0 6202.5 5692.1 5761.7 5782.7 5789.8 5820.9 6094.5 6145.7 6246.5 6252.2 6253.0 6060.8 6096.1 6107.8 6109.5 6116.2 5180.0 6082.7 6083.2 6166.5 6175.9 6076.3 6104.6 6119.7 6148.6 6148.8 6123.1 6133.6 6134.2 6142.5 6147.8 4744.1 4814.2 4816.2 4829.5 4862.5 4319.1 4360.3 4374.9 4390.2 4431.3 5718.6 5753.2 5759.2 5762.3 5764.2 6121.1 6244.6 6258.6 6259.2 6259.8 5601.2 5691.8 5694.0 5699.7 5700.8 5285.1 5295.0 5299.9 5303.5 5307.8 6225.8 6251.9 6308.9 6319.9 6326.4 6203.8 6221.6 6265.8 6275.6 6277.5 5078.1 6293.5 6329.7 6336.2 6338.7 5892.7 6078.9 6128.6 6142.9 6154.5 5136.7 5167.6 6197.5 6218.6 6230.4 6050.0 6066.0 6066.5 6068.4 6069.2 5932.0 6020.4 6030.2 6065.6 6109.6 5894.6 5898.5 5914.7 5915.5 5949.9 6070.1 6075.8 6096.6 6098.4 6107.9 4915.4 5775.0 5807.1 5898.6 5908.8 19:33:41 INFO - PROCESS | 1667 | 19:33:41 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:65762.68 Median:21192.81 stddev:138484.07 (653.4%) stddev-sans-first:138785.76 19:33:41 INFO - PROCESS | 1667 | Values: 460.8 562.9 580.8 595.4 642.0 26825.0 38302.8 38326.6 38534.0 38538.6 41374.6 41788.0 41872.0 41901.8 41941.6 9536.3 9723.4 9734.3 9741.7 9745.3 9457.4 9582.7 9614.3 9619.6 9764.7 9704.0 9756.6 9775.1 9790.5 9792.5 9702.7 9780.8 9781.8 9786.7 9820.4 9069.3 9199.7 9216.4 9216.5 9220.5 40185.6 40485.0 40657.0 40657.0 40661.4 3168.4 3622.6 3851.6 3917.5 3993.2 28407.0 29146.9 29942.9 30042.8 30082.0 2711.7 2777.7 2802.6 2846.2 2859.9 499483.0 504780.0 506260.0 506394.0 506700.0 63329.7 63354.4 63852.7 63896.0 63925.0 65896.0 66073.3 66114.0 66175.0 66241.3 17129.6 17285.6 17325.6 17342.7 17353.6 13284.3 13304.2 13329.4 13329.7 13342.3 18799.5 19352.9 19611.8 19656.6 19695.1 7613.0 8821.3 8896.9 8903.4 9234.7 941.8 1002.7 1023.3 1067.0 1069.7 27566.4 27657.3 28084.0 28662.7 28830.3 1068.5 1107.2 1119.3 1145.1 1241.8 14609.3 15175.0 15284.6 15580.0 15724.7 536277.0 547628.0 549321.3 549614.0 549737.0 58552.1 59407.7 59432.4 59544.1 59631.7 1340.9 1360.7 1361.0 1490.3 1552.3 23931.5 28358.4 29025.3 29762.7 30015.8 54300.0 54441.7 54693.7 54809.5 54839.7 497136.0 506109.0 510387.0 511236.0 511851.0 27357.0 31577.8 31581.7 32040.2 32048.9 38435.0 39054.8 39287.4 39492.6 39947.6 33643.8 35175.5 35615.3 35637.0 36036.3 34037.0 34450.7 34486.1 35143.0 35224.9 34219.8 34839.5 35180.3 35401.8 35496.5 30388.7 30546.2 30744.0 30746.8 30751.0 20403.5 21403.7 23132.8 23571.4 23789.0 19:33:41 INFO - PROCESS | 1667 | 19:33:41 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:36076.45 Median:14401.76 stddev:105908.33 (735.4%) stddev-sans-first:106171.48 19:33:41 INFO - PROCESS | 1667 | Values: 361.9 366.6 368.9 369.7 376.7 16003.0 19282.3 19775.0 19792.6 19806.5 19420.4 19692.5 20866.0 20923.0 20928.7 5168.1 5315.9 5348.8 5357.5 5357.5 3935.7 4115.8 5270.2 7197.2 7523.3 7384.7 7527.5 7530.5 7538.4 7549.7 7328.3 7452.9 7551.3 7560.4 7570.4 4355.9 4379.2 6856.8 7078.6 7180.1 25951.5 26100.0 27805.9 29219.3 29371.3 2403.3 2423.0 2443.6 2703.5 3008.8 15330.3 15392.2 15919.4 16337.7 16374.5 1638.8 1705.4 1933.5 1964.2 2034.2 104078.0 106944.0 107165.9 108219.5 109931.5 18741.0 19299.0 19591.5 19595.3 19900.4 29086.3 29230.3 29286.7 29300.9 29303.0 10647.3 11018.6 11032.9 11060.4 11147.9 9361.2 9498.3 9521.9 9583.9 9625.0 8829.8 8875.4 8957.6 8958.3 8988.9 2971.7 3177.4 3411.5 3467.6 3512.2 792.7 940.4 948.3 1009.5 1047.2 19252.5 19851.6 19852.0 19933.7 19941.4 929.0 929.8 947.6 1008.1 1045.8 10109.6 10609.9 10619.4 10623.4 10636.2 628806.0 631912.0 641548.0 653772.0 654754.0 107238.0 108659.0 108782.5 110080.0 111771.4 1002.0 1014.3 1032.8 1053.7 1054.2 14471.2 17170.1 17333.0 17535.0 17624.5 49278.8 49425.0 50104.7 50597.5 52230.1 68127.0 69058.7 69436.7 71572.0 71703.2 7501.1 7654.2 7768.3 7774.6 7832.7 9278.8 11643.9 11821.2 12126.3 12144.2 8853.8 9568.2 10384.5 10578.9 10613.0 10261.4 10331.0 10355.9 10488.8 10617.9 10111.4 10272.3 10496.3 10859.9 11207.9 7243.8 7707.7 7821.0 7907.9 7950.6 10433.3 10755.6 10960.1 11021.2 11087.2 19:33:41 INFO - PROCESS | 1667 | 19:33:41 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5978.38 Median:5443.81 stddev:3099.43 (56.9%) stddev-sans-first:3089.12 19:33:41 INFO - PROCESS | 1667 | Values: 1412.7 1588.6 1626.2 1908.5 2028.9 7799.7 8445.6 8591.3 8602.8 8643.8 8341.4 8582.3 8596.4 8596.8 8613.5 2148.8 2383.6 2444.6 2473.0 2486.1 2468.8 2475.9 2476.5 2479.5 2479.8 2389.7 2420.9 2424.3 2457.1 2461.3 2415.8 2441.6 2453.4 2462.9 2474.7 2447.8 2453.0 2455.1 2474.6 2475.5 8676.7 8747.5 8747.6 8760.4 8766.6 5243.6 5410.2 5417.5 5441.1 5442.7 3544.8 3623.0 3693.9 3711.4 3723.1 1000.2 1020.2 1029.3 1029.8 1030.6 11650.1 11670.6 11673.4 11711.1 11714.5 4729.5 4805.9 4897.4 4904.1 4911.8 9930.2 9936.3 9936.7 9944.9 9963.8 5382.3 5441.8 5444.9 5447.8 5456.9 4364.7 4473.0 4476.6 4487.5 4949.8 3820.5 3822.3 3903.8 3905.1 3913.1 1109.0 1254.5 1291.8 1296.1 1303.1 1335.5 1337.2 1368.6 1410.5 1411.2 7876.7 8058.0 8061.7 8107.4 8129.0 1185.6 1199.4 1205.9 1212.6 1227.0 6194.4 6227.6 6283.6 6284.9 6312.1 10136.8 10499.8 10601.7 10619.2 10637.0 9862.7 10399.3 10468.7 10511.2 10513.1 8038.4 8054.2 8178.5 8181.9 8189.4 7859.7 7900.0 7927.5 7969.0 7972.6 6648.9 9204.9 9254.4 9322.7 9454.5 9178.4 9366.1 9720.6 9738.0 9945.1 6697.9 7388.5 7481.4 7513.8 7560.4 7723.6 8177.7 8216.5 8220.2 8288.0 6637.2 7077.7 7262.4 7285.0 7435.3 7208.3 7238.9 7239.2 7351.6 7380.3 7690.1 7693.9 7754.0 7789.0 7792.0 7000.9 7029.5 7052.6 7056.9 7057.3 7048.8 7117.9 7140.9 7142.3 7188.5 19:33:41 INFO - PROCESS | 1667 | 19:33:41 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:5675.50 Median:6544.01 stddev:1817.73 (27.8%) stddev-sans-first:1778.01 19:33:41 INFO - PROCESS | 1667 | Values: 330.8 332.8 337.4 339.7 344.7 6515.8 6531.0 6541.6 6546.4 6551.7 5216.9 6468.6 6471.2 6473.8 6475.7 4635.7 4715.0 4737.1 4843.6 4889.8 4777.3 4805.9 4819.2 4823.7 4848.3 4441.8 4755.8 4757.3 4776.5 4809.4 3859.0 4606.8 4682.3 4796.6 4807.1 4123.4 4130.0 4133.3 4138.7 4139.4 6150.4 6200.3 6204.4 6207.8 6210.7 5606.0 5619.2 5623.5 5628.5 5653.5 6857.4 6874.9 6886.7 6887.7 6889.3 4312.5 4468.1 4482.4 4491.1 4623.3 7185.3 7191.9 7227.3 7229.3 7248.6 4531.9 4542.3 4544.9 4547.2 4548.3 4601.6 4605.3 4608.3 4615.2 4627.1 4816.0 5177.2 5458.1 5484.3 5487.0 6595.1 6888.2 6890.8 6901.9 6923.9 1587.2 1596.1 1610.2 1613.7 1614.6 973.9 1019.9 1027.5 1044.3 1055.7 6440.0 6492.4 6635.9 6638.9 6655.8 7189.7 7211.4 7217.2 7223.0 7226.7 6593.0 6608.0 6614.9 6631.1 6642.5 3757.1 3806.0 3808.6 3811.5 3813.4 7228.9 7546.0 7572.4 7572.7 7586.2 6024.2 7339.3 7362.0 7378.2 7387.9 7529.7 7541.5 7562.5 7563.7 7571.8 6899.4 7178.2 7192.5 7194.2 7200.9 7039.9 7073.6 7100.1 7108.8 7119.5 7480.9 7536.1 7544.6 7548.5 7557.4 4563.7 4566.9 4567.7 4578.4 4590.4 6891.9 7182.0 7184.5 7184.7 7192.0 6929.3 6932.7 6943.4 6943.7 6949.6 6849.8 6909.7 6915.8 6920.5 6927.5 6753.2 6758.2 6764.1 6768.2 6788.3 6678.7 6897.4 6925.9 6946.6 6948.2 6469.0 6606.9 6611.3 6774.7 6787.9 19:33:41 INFO - PROCESS | 1667 | 19:33:41 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17188.89 Median:3782.48 stddev:24071.31 (636.4%) stddev-sans-first:24106.84 19:33:41 INFO - PROCESS | 1667 | Values: 660.7 678.7 688.8 781.1 821.7 14911.6 15204.3 15266.6 15267.8 15281.0 11284.5 14049.4 14061.6 14064.4 14078.2 905.6 923.7 927.0 929.5 932.6 739.2 749.8 920.0 920.3 922.6 965.5 988.7 991.3 992.4 992.6 972.6 978.0 980.9 983.9 985.9 580.6 718.2 734.6 736.0 737.1 15012.7 15014.9 15056.8 15077.5 15134.6 12446.8 12461.4 12491.3 12516.7 12614.8 9665.1 9780.8 9805.3 10014.2 10022.4 3292.5 3355.6 3402.0 3422.5 3459.4 71220.6 71245.0 71614.6 71854.3 72455.6 9422.7 9482.2 9497.2 9676.9 9687.5 22270.3 22277.0 22279.2 22320.6 22343.9 7276.5 7373.6 7386.0 7483.8 7502.7 3855.2 3999.0 4001.5 4018.9 4048.0 2990.9 3454.5 3514.6 3519.1 3523.5 671.4 676.2 689.1 691.0 691.8 500.3 512.4 528.5 529.4 579.9 7163.0 7273.2 7539.7 7717.3 7801.7 491.4 545.8 557.7 581.3 598.2 3388.2 3410.3 3456.6 3502.1 3567.2 78836.0 79147.8 79362.0 92379.5 93113.1 69877.0 70028.0 70559.0 78602.0 79465.0 66853.7 67085.7 67189.7 67282.0 67949.0 6586.1 6722.5 6742.3 6876.2 6988.5 44675.3 45264.6 45364.5 50893.5 51016.5 58941.0 63578.7 69928.6 70143.6 71053.3 8994.8 9002.7 9224.0 9237.5 9688.8 9841.1 10294.2 10338.6 10493.9 11390.4 8834.1 9316.0 9822.5 9887.5 10063.8 10040.3 10225.0 10255.2 10284.9 10343.4 9792.4 9868.8 10043.0 10051.8 10054.4 8433.7 8449.8 8558.6 8645.9 8722.4 8460.8 8498.7 8512.5 8586.0 8621.6 19:33:41 INFO - PROCESS | 1667 | -------- Summary: end -------- 19:33:41 INFO - PROCESS | 1667 | 19:33:41 INFO - TEST-INFO | 1667: exit 0 19:33:41 INFO - TEST-OK | dromaeo_css | took 1113820ms 19:33:41 INFO - TEST-START | kraken 19:33:41 INFO - operating with platform_type : mac_ 19:33:41 INFO - Initialising browser for kraken test... 19:33:41 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/tmpah2DZv/profile http://localhost:49274/getInfo.html) 19:33:44 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 19:33:44 INFO - PROCESS | 1677 | colorDepth:24 19:33:44 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 19:33:44 INFO - PROCESS | 1677 | __metrics 19:33:44 INFO - PROCESS | 1677 | 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" 19:33:44 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:33:44 INFO - TEST-INFO | 1677: exit 0 19:33:44 INFO - Browser initialized. 19:33:44 INFO - Running cycle 1/1 for kraken test... 19:33:44 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/tmpah2DZv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:33:56 INFO - PROCESS | 1679 | RSS: Main: 243322880 19:33:56 INFO - PROCESS | 1679 | 19:33:59 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 19:33:59 INFO - PROCESS | 1679 | RSS: Main: 288899072 19:33:59 INFO - PROCESS | 1679 | 19:34:02 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 19:34:03 INFO - PROCESS | 1679 | RSS: Main: 301965312 19:34:03 INFO - PROCESS | 1679 | 19:34:06 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 19:34:06 INFO - PROCESS | 1679 | RSS: Main: 285523968 19:34:06 INFO - PROCESS | 1679 | 19:34:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 19:34:09 INFO - PROCESS | 1679 | RSS: Main: 287055872 19:34:09 INFO - PROCESS | 1679 | 19:34:11 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:34:12 INFO - PROCESS | 1679 | RSS: Main: 288890880 19:34:12 INFO - PROCESS | 1679 | 19:34:14 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 19:34:15 INFO - PROCESS | 1679 | RSS: Main: 290373632 19:34:15 INFO - PROCESS | 1679 | 19:34:18 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 19:34:19 INFO - PROCESS | 1679 | RSS: Main: 290496512 19:34:19 INFO - PROCESS | 1679 | 19:34:21 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 19:34:22 INFO - PROCESS | 1679 | RSS: Main: 290889728 19:34:22 INFO - PROCESS | 1679 | 19:34:24 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:34:24 INFO - PROCESS | 1679 | RSS: Main: 325537792 19:34:24 INFO - PROCESS | 1679 | 19:34:26 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:34:27 INFO - PROCESS | 1679 | RSS: Main: 323485696 19:34:27 INFO - PROCESS | 1679 | 19:34:29 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:34:29 INFO - PROCESS | 1679 | RSS: Main: 332443648 19:34:29 INFO - PROCESS | 1679 | 19:34:32 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:34:33 INFO - PROCESS | 1679 | RSS: Main: 334282752 19:34:33 INFO - PROCESS | 1679 | 19:34:35 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:34:36 INFO - PROCESS | 1679 | RSS: Main: 334053376 19:34:36 INFO - PROCESS | 1679 | 19:34:37 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 19:34:38 INFO - PROCESS | 1679 | RSS: Main: 394739712 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | __start_tp_report 19:34:38 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 19:34:38 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 19:34:38 INFO - PROCESS | 1679 | |i|pagename|runs| 19:34:38 INFO - PROCESS | 1679 | |0;ai-astar;109;108;111;109;111;110;117;111;107;111 19:34:38 INFO - PROCESS | 1679 | |1;audio-beat-detection;157;100;149;193;147;144;139;117;158;127 19:34:38 INFO - PROCESS | 1679 | |2;audio-dft;186;185;183;187;183;183;185;187;181;178 19:34:38 INFO - PROCESS | 1679 | |3;audio-fft;74;77;83;76;56;57;57;80;76;57 19:34:38 INFO - PROCESS | 1679 | |4;audio-oscillator;95;93;128;81;78;83;112;76;109;147 19:34:38 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;89;85;88;85;87;85;89;86;88;85 19:34:38 INFO - PROCESS | 1679 | |6;imaging-darkroom;93;108;127;142;148;93;93;94;93;92 19:34:38 INFO - PROCESS | 1679 | |7;imaging-desaturate;87;87;85;84;87;87;107;143;116;88 19:34:38 INFO - PROCESS | 1679 | |8;json-parse-financial;55;56;55;55;55;56;55;66;57;52 19:34:38 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;64;80;93;113;97;70;69;70;69;69 19:34:38 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;83;82;83;81;84;78;81;82;84;83 19:34:38 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;120;130;125;130;120;132;128;139;110;131 19:34:38 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;138;138;134;135;143;124;137;137;137;138 19:34:38 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;67;66;65;68;69;67;66;68;68;66 19:34:38 INFO - PROCESS | 1679 | __end_tp_report 19:34:38 INFO - PROCESS | 1679 | __start_cc_report 19:34:38 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1280 19:34:38 INFO - PROCESS | 1679 | __end_cc_report 19:34:38 INFO - PROCESS | 1679 | __startTimestamp1458095678164__endTimestamp 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | ------- Summary: start ------- 19:34:38 INFO - PROCESS | 1679 | Number of tests: 14 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:2.72 (2.4%) stddev-sans-first:2.83 19:34:38 INFO - PROCESS | 1679 | Values: 109.0 108.0 111.0 109.0 111.0 110.0 117.0 111.0 107.0 111.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:143.10 Median:148.00 stddev:25.32 (17.1%) stddev-sans-first:26.35 19:34:38 INFO - PROCESS | 1679 | Values: 157.0 100.0 149.0 193.0 147.0 144.0 139.0 117.0 158.0 127.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:183.80 Median:185.00 stddev:2.82 (1.5%) stddev-sans-first:2.88 19:34:38 INFO - PROCESS | 1679 | Values: 186.0 185.0 183.0 187.0 183.0 183.0 185.0 187.0 181.0 178.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:69.30 Median:76.00 stddev:11.08 (14.6%) stddev-sans-first:11.62 19:34:38 INFO - PROCESS | 1679 | Values: 74.0 77.0 83.0 76.0 56.0 57.0 57.0 80.0 76.0 57.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:100.20 Median:79.50 stddev:23.57 (29.7%) stddev-sans-first:24.93 19:34:38 INFO - PROCESS | 1679 | Values: 95.0 93.0 128.0 81.0 78.0 83.0 112.0 76.0 109.0 147.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:1.70 (1.9%) stddev-sans-first:1.59 19:34:38 INFO - PROCESS | 1679 | Values: 89.0 85.0 88.0 85.0 87.0 85.0 89.0 86.0 88.0 85.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:108.30 Median:93.00 stddev:22.28 (24.0%) stddev-sans-first:22.93 19:34:38 INFO - PROCESS | 1679 | Values: 93.0 108.0 127.0 142.0 148.0 93.0 93.0 94.0 93.0 92.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:87.00 stddev:19.35 (22.2%) stddev-sans-first:20.18 19:34:38 INFO - PROCESS | 1679 | Values: 87.0 87.0 85.0 84.0 87.0 87.0 107.0 143.0 116.0 88.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:55.50 stddev:3.68 (6.6%) stddev-sans-first:3.87 19:34:38 INFO - PROCESS | 1679 | Values: 55.0 56.0 55.0 55.0 55.0 56.0 55.0 66.0 57.0 52.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:79.40 Median:70.00 stddev:16.20 (23.1%) stddev-sans-first:16.20 19:34:38 INFO - PROCESS | 1679 | Values: 64.0 80.0 93.0 113.0 97.0 70.0 69.0 70.0 69.0 69.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:83.00 stddev:1.79 (2.2%) stddev-sans-first:1.87 19:34:38 INFO - PROCESS | 1679 | Values: 83.0 82.0 83.0 81.0 84.0 78.0 81.0 82.0 84.0 83.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:130.00 stddev:8.11 (6.2%) stddev-sans-first:8.26 19:34:38 INFO - PROCESS | 1679 | Values: 120.0 130.0 125.0 130.0 120.0 132.0 128.0 139.0 110.0 131.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.10 Median:137.50 stddev:4.86 (3.5%) stddev-sans-first:5.11 19:34:38 INFO - PROCESS | 1679 | Values: 138.0 138.0 134.0 135.0 143.0 124.0 137.0 137.0 137.0 138.0 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.25 (1.8%) stddev-sans-first:1.32 19:34:38 INFO - PROCESS | 1679 | Values: 67.0 66.0 65.0 68.0 69.0 67.0 66.0 68.0 68.0 66.0 19:34:38 INFO - PROCESS | 1679 | -------- Summary: end -------- 19:34:38 INFO - PROCESS | 1679 | 19:34:38 INFO - TEST-INFO | 1679: exit 0 19:34:38 INFO - TEST-OK | kraken | took 57030ms 19:34:38 INFO - SUITE-END | took 1170s 19:34:39 INFO - Completed test suite (00:19:31) 19:34:39 INFO - javascript benchmark 19:34:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3014.8944786932457, 3489.842957066933, 3533.1520435095586, 3541.1678255609927, 3545.096000519867, 5320.334958130234, 5546.805861794838, 5609.971950140254, 5636.7181640918, 5649.689267090308, 5621.943780562193, 5645.689487078219, 5660.29246344207, 5780.0751879699255, 5794.942050579493, 5073.873153171164, 5094.1594636884865, 5120.334356533645, 5126.871828204287, 5235.293235413209, 5196.220566914974, 5216.295800067, 5269.130593452076, 5280.815171468999, 5316.654417462872, 5071.923921141186, 5077.339945807058, 5132.461091585367, 5165.4834516548235, 5216.95660867828, 5181.766820493087, 5192.636515443921, 5203.505666961632, 5214.878801057791, 5240.816571419982, 5116.795328186868, 5144.0740666679985, 5172.9741351293005, 5174.24973378858, 5186.403563590197, 5453.918191227135, 5544.0575102232715, 5574.637648552872, 5579.6652200868075, 5580.832575022756, 6089.420592079687, 6095.05526643371, 6104.938950610482, 6111.847203819895, 6163.537734669918, 5143.717095559743, 6164.722587483574, 6172.35190305016, 6192.969035154841, 6202.472789812871, 5692.1461780733225, 5761.740721667535, 5782.721292281426, 5789.768409263583, 5820.941790582082, 6094.481969032593, 6145.740123274718, 6246.46905013079, 6252.155958945484, 6253.0, 6060.818175454743, 6096.055111457731, 6107.755689772448, 6109.450149486567, 6116.174316467309, 5180.0, 6082.695865206722, 6083.209182806296, 6166.475849552839, 6175.938240617536, 6076.270847498328, 6104.603200791934, 6119.685352982247, 6148.600340977822, 6148.78479253224, 6123.120160253514, 6133.55976224962, 6134.19168101403, 6142.539309551801, 6147.784827531015, 4744.146053710297, 4814.181589129857, 4816.15717249487, 4829.517048295213, 4862.465128835825, 4319.092990471973, 4360.302351623725, 4374.868753937387, 4390.187815254124, 4431.290993441034, 5718.628289161191, 5753.165790960287, 5759.1937128802, 5762.250907382014, 5764.221830052889, 6121.112438696453, 6244.594101383395, 6258.624482531062, 6259.186305780219, 6259.843503912439, 5601.243832082617, 5691.800786972519, 5693.971530142323, 5699.715014249271, 5700.7719691212715, 5285.101532739443, 5295.046891559479, 5299.920501192485, 5303.469653034743, 5307.761150748225, 6225.844353891189, 6251.906221406683, 6308.936910630835, 6319.873602528016, 6326.430621244018, 6203.7828675996125, 6221.564490485623, 6265.843353916189, 6275.560710750204, 6277.497800176066, 5078.111330517218, 6293.527985401021, 6329.746810127727, 6336.20797400325, 6338.651374174465, 5892.734826932884, 6078.878422431615, 6128.63228206309, 6142.867137759864, 6154.507639388748, 5136.691798492103, 5167.58659307247, 6197.504199664107, 6218.626882387071, 6230.43926046658, 6050.0, 6065.999110146777, 6066.4540191382985, 6068.393160683896, 6069.2110025696375, 5931.991561434653, 6020.4280593343565, 6030.185924900258, 6065.60573562717, 6109.633421994695, 5894.557908156819, 5898.469137777621, 5914.67469289176, 5915.526757859447, 5949.91075133882, 6070.0591408331775, 6075.756969721162, 6096.568695548639, 6098.359672234529, 6107.9389206109145, 4915.43472500667, 5775.018246897954, 5807.070868660994, 5898.616589921641, 5908.793192238423], "unit": "score", "name": "dojo.html", "value": 5624.064786084917}, {"lowerIsBetter": false, "replicates": [460.7972492103476, 562.9155626656, 580.8373655376494, 595.3957985095835, 642.0273285971753, 26825.0, 38302.80848595757, 38326.616733832685, 38534.0, 38538.61461385385, 41374.58625413745, 41788.0, 41872.0, 41901.79049104758, 41941.58058419415, 9536.332456728032, 9723.416595004323, 9734.26992975526, 9741.659041933533, 9745.269104817167, 9457.385269957465, 9582.664606738765, 9614.278929080312, 9619.615215391412, 9764.707058788248, 9704.029597040275, 9756.609735610567, 9775.120239178472, 9790.510474476283, 9792.461414622192, 9702.70891873245, 9780.755481112958, 9781.755456113582, 9786.706398808046, 9820.41077535343, 9069.31980101495, 9199.724008279762, 9216.447013179231, 9216.49309287989, 9220.492872891988, 40185.59814401848, 40485.0, 40657.0, 40657.0, 40661.390079148834, 3168.429682657121, 3622.6377362263825, 3851.614838516154, 3917.4515567820526, 3993.201359728066, 28407.0, 29146.854265728747, 29942.85028574843, 30042.849785750932, 30082.0, 2711.701712811589, 2777.6805667348312, 2802.607634931111, 2846.1746093632823, 2859.9428011439863, 499483.0, 504780.0, 506260.0, 506394.0, 506700.0, 63329.68335158295, 63354.36645633577, 63852.68073659602, 63896.041559376645, 63925.0, 65896.0, 66073.33926660768, 66114.0, 66175.0, 66241.34396640027, 17129.571760706083, 17285.56786080358, 17325.56686082858, 17342.739858902125, 17353.56616084608, 13284.269365185008, 13304.201747895157, 13329.400176992058, 13329.66675833112, 13342.332883355793, 18799.53691334935, 19352.903235483733, 19611.803881961285, 19656.606867862563, 19695.11371988264, 7612.961935190399, 8821.338399620054, 8896.911030889738, 8903.421277617064, 9234.722958311262, 941.8210539997378, 1002.7142264454595, 1023.3348323589727, 1066.983995240072, 1069.6790962711154, 27566.44867102647, 27657.308567285978, 28084.0, 28662.713372866423, 28830.279243018675, 1068.4711068021224, 1107.1585594947899, 1119.322809700136, 1145.118258940611, 1241.7951038078616, 14609.342579583945, 15175.013624114397, 15284.617884552752, 15580.0, 15724.68550628981, 536277.0, 547628.0, 549321.2533937305, 549614.0, 549737.0, 58552.12171817341, 59407.702961484916, 59432.405675943555, 59544.106838397456, 59631.70184149052, 1340.8659134086513, 1360.7074478987065, 1361.0200655528004, 1490.3070072416378, 1552.3169805285638, 23931.52136957216, 28358.43283134367, 29025.274368140967, 29762.702372975993, 30015.849920750257, 54300.0, 54441.72779136079, 54693.72653136709, 54809.45190548043, 54839.72580137074, 497136.0, 506109.0, 510387.0, 511236.0, 511851.0, 27357.0, 31577.842110789297, 31581.684183157875, 32040.198995025312, 32048.878289259763, 38435.0, 39054.80472597619, 39287.41068883912, 39492.60507395004, 39947.60052399555, 33643.831780841916, 35175.47236791501, 35615.287694246486, 35637.0, 36036.27927441489, 34037.0, 34450.65549344474, 34486.13784655404, 35143.0, 35224.94325170249, 34219.82890085533, 34839.47740783941, 35180.29639407249, 35401.822990885914, 35496.46755298621, 30388.696113038586, 30546.236344091576, 30744.0, 30746.846265768527, 30751.0, 20403.4899127523, 21403.73880048493, 23132.83608950405, 23571.41071473227, 23789.0], "unit": "score", "name": "ext.html", "value": 19782.265793339204}, {"lowerIsBetter": false, "replicates": [361.8751530721901, 366.5967435820596, 368.93174762668906, 369.70423661071135, 376.7475791219882, 16003.0, 19282.32511862085, 19775.011249437506, 19792.604147917034, 19806.504837379056, 19420.417387478363, 19692.50768730779, 20866.0, 20923.0, 20928.68606970893, 5168.121419358699, 5315.893682126355, 5348.812791552296, 5357.464253574651, 5357.517823395893, 3935.724499285051, 4115.814788334517, 5270.183121616155, 7197.172325182619, 7523.307255867413, 7384.704611815548, 7527.47307688462, 7530.472866899319, 7538.359239464652, 7549.735759248428, 7328.30381113796, 7452.925470745277, 7551.282628150317, 7560.3573696235335, 7570.394368450538, 4355.891102722426, 4379.233634114049, 6856.760013399507, 7078.610676412795, 7180.102487189101, 25951.480970380675, 26100.0, 27805.8877644896, 29219.269518262, 29371.265718356997, 2403.3030421177837, 2422.9636555451684, 2443.5601591713485, 2703.5133675938323, 3008.7893847430687, 15330.310136043905, 15392.230388480642, 15919.442819501377, 16337.673246535003, 16374.508764737076, 1638.7541868719788, 1705.377537198926, 1933.5456167800555, 1964.1848632817364, 2034.216826521785, 104078.0, 106943.99999999923, 107165.92834071637, 108219.45890270577, 109931.45034274858, 18741.0, 19299.0, 19591.510212244808, 19595.31416400419, 19900.402987910096, 29086.272843178667, 29230.26924326909, 29286.707132928823, 29300.853495732386, 29303.0, 10647.254692171473, 11018.559257629766, 11032.944835275934, 11060.391678457601, 11147.940945610155, 9361.229658929247, 9498.287628427895, 9521.90478095224, 9583.904160958302, 9624.951875240578, 8829.823403532022, 8875.423097498771, 8957.641694332153, 8958.328125390623, 8988.865167022499, 2971.682087971386, 3177.4280629486916, 3411.5053317269353, 3467.601225859008, 3512.2448673535314, 792.6683172270641, 940.3793496292415, 948.2650945517279, 1009.4548943570536, 1047.1831971062584, 19252.518687032654, 19851.60296794085, 19852.00739963025, 19933.704309219855, 19941.401757947286, 928.9860652090224, 929.7582628516499, 947.6256878533079, 1008.1027885182194, 1045.811753884293, 10109.64616238428, 10609.9469502652, 10619.36283822973, 10623.415712135908, 10636.202284828669, 628806.0, 631912.0, 641548.0, 653772.0, 654754.0, 107238.0, 108659.0, 108782.45608771905, 110080.0, 111771.44114279376, 1002.0280328081749, 1014.3001329083056, 1032.8244198486275, 1053.6628278950661, 1054.1566746602687, 14471.204083775494, 17170.141492925555, 17333.0, 17535.035573043606, 17624.471265862045, 49278.75360623317, 49425.0, 50104.749476252386, 50597.49402505928, 52230.1273429965, 68127.0, 69058.65470672614, 69436.65281673559, 71572.0, 71703.20741981284, 7501.099868015873, 7654.196309387657, 7768.262015108556, 7774.611269436618, 7832.74676051829, 9278.793756811572, 11643.941780291045, 11821.172517923662, 12126.333051682243, 12144.210626309261, 8853.80473636051, 9568.234541236823, 10384.532696028546, 10578.947105264733, 10613.0, 10261.384316941007, 10331.0, 10355.896441035493, 10488.790224195322, 10617.946910265398, 10111.443870587189, 10272.33229840088, 10496.265261431932, 10859.945700271448, 11207.887921121011, 7243.782686519413, 7707.703740401288, 7821.0, 7907.920920790719, 7950.64222110018, 10433.32183408076, 10755.623553175601, 10960.068394186583, 11021.173411994292, 11087.22389432732], "unit": "score", "name": "jquery.html", "value": 10377.624463055174}, {"lowerIsBetter": false, "replicates": [1412.6750847305113, 1588.555204542728, 1626.2031604513788, 1908.5037890148558, 2028.9391318260448, 7799.727009554666, 8445.61994710238, 8591.269742071916, 8602.784930376736, 8643.783905402352, 8341.374396920224, 8582.313414926806, 8596.398252122339, 8596.785080372994, 8613.483191008543, 2148.79586439288, 2383.6067048936907, 2444.62108373202, 2472.9752702473015, 2486.1298545509107, 2468.790152837011, 2475.8762061896923, 2476.4923190745853, 2479.47930934504, 2479.776820086192, 2389.7499400814877, 2420.9031638734427, 2424.309071914505, 2457.0908763757348, 2461.323136137559, 2415.7946574541184, 2441.645961335605, 2453.423445490317, 2462.9014839406404, 2474.7401522840023, 2447.7674620910984, 2453.0065323543995, 2455.091616102036, 2474.6288056791445, 2475.5048990201853, 8676.696315628986, 8747.518886461243, 8747.562621868945, 8760.386772925898, 8766.649334026632, 5243.580513558906, 5410.161424979135, 5417.51242388183, 5441.129419292934, 5442.700651464168, 3544.769589976643, 3623.0036739896477, 3693.9445908311395, 3711.4247291669835, 3723.1250656095795, 1000.1798525209331, 1020.209337763232, 1029.25893356783, 1029.763154474475, 1030.6032177611598, 11650.067994560415, 11670.591529296518, 11673.357965311905, 11711.063114950783, 11714.53141874324, 4729.491552351559, 4805.927911081337, 4897.363342765418, 4904.092742842514, 4911.803527858917, 9930.2055835533, 9936.254780891462, 9936.701898943043, 9944.850827237597, 9963.750906227257, 5382.30030096085, 5441.809536666197, 5444.911017796378, 5447.809326673627, 5456.890862182734, 4364.738115713068, 4472.9552704472535, 4476.574725401082, 4487.506374298824, 4949.82675606352, 3820.465134881119, 3822.3310920588788, 3903.8048097595006, 3905.062784931596, 3913.0804260998643, 1109.0, 1254.4668515880896, 1291.8449786025737, 1296.1445446005591, 1303.1269049736509, 1335.4991878045732, 1337.2177276293282, 1368.568900796246, 1410.4992727581766, 1411.2449839335907, 7876.72431464896, 8058.033036035713, 8061.677532898736, 8107.391945603986, 8128.983877015373, 1185.5909711149638, 1199.3763243113706, 1205.9397030148457, 1212.6422705301957, 1227.030645789834, 6194.38056194386, 6227.626342419469, 6283.622982621057, 6284.874302513924, 6312.147860038928, 10136.847947280796, 10499.790004199873, 10601.681949541526, 10619.20355973305, 10637.0, 9862.654807081717, 10399.272050956512, 10468.68593942183, 10511.211659125596, 10513.053825155772, 8038.437309388403, 8054.23484768946, 8178.5092894426525, 8181.854540364354, 8189.426740128134, 7859.685612575433, 7900.0, 7927.484713493604, 7969.0, 7972.561509117054, 6648.933510664831, 9204.907950920406, 9254.35219534626, 9322.673706420243, 9454.527273636428, 9178.357514973888, 9366.06339366058, 9720.562574684014, 9737.951310243687, 9945.055219754111, 6697.899531506934, 7388.519746216479, 7481.438892083007, 7513.774586762406, 7560.3573696236435, 7723.575203363869, 8177.713780017671, 8216.46592971455, 8220.219079187467, 8288.046874609498, 6637.203535575762, 7077.68150433242, 7262.419006479363, 7284.963575182091, 7435.256474352522, 7208.315210055036, 7238.8914166286495, 7239.167495738057, 7351.595662238628, 7380.261973802577, 7690.077190737147, 7693.923060769321, 7754.0, 7788.961055194688, 7791.987041684545, 7000.894986575104, 7029.5430796998035, 7052.612106334201, 7056.858862822817, 7057.329553692391, 7048.753293634698, 7117.857642847218, 7140.85718285621, 7142.285771423024, 7188.4968052235845], "unit": "score", "name": "mootools.html", "value": 4909.211338046258}, {"lowerIsBetter": false, "replicates": [330.80482515315964, 332.8285932744637, 337.37417091295663, 339.6773065587691, 344.73972151025987, 6515.837104072395, 6531.0, 6541.607503549791, 6546.410823025927, 6551.672416379186, 5216.9478305217035, 6468.579542329745, 6471.158749362588, 6473.773417930373, 6475.676216189184, 4635.675502714811, 4714.976425117869, 4737.147313483571, 4843.588294994912, 4889.755512224384, 4777.28340748887, 4805.879853003668, 4819.228923372261, 4823.686460380081, 4848.2727590861305, 4441.778510909493, 4755.762211889409, 4757.286407038954, 4776.522347765231, 4809.398825146856, 3859.0, 4606.838760643395, 4682.27424749161, 4796.592289655384, 4807.062622788558, 4123.36087906375, 4130.0, 4133.256013917494, 4138.710290279681, 4139.4204811326435, 6150.415710507495, 6200.286966998808, 6204.441600255954, 6207.782727604512, 6210.689465526705, 5605.971970140164, 5619.157126431027, 5623.522000629951, 5628.521575666074, 5653.491185793298, 6857.417119544844, 6874.931250687529, 6886.724531018804, 6887.690053947585, 6889.345512176335, 4312.460942382202, 4468.0840427712565, 4482.439695038121, 4491.124230775, 4623.329617205486, 7185.317394847481, 7191.928080719178, 7227.349538541556, 7229.349358557755, 7248.601326927017, 4531.909361812811, 4542.341360502709, 4544.886377840514, 4547.249703798902, 4548.340490628906, 4601.596513063548, 4605.286180642006, 4608.308753686973, 4615.16926953145, 4627.120847039052, 4815.95184048155, 5177.197534382177, 5458.1169496523635, 5484.2596249506605, 5486.972565137149, 6595.109660195908, 6888.207856096613, 6890.758823441155, 6901.930980690229, 6923.930760692429, 1587.190532828266, 1596.106180538902, 1610.1627153879917, 1613.6772645470953, 1614.5559971008067, 973.8636590877283, 1019.928604997643, 1027.4965267019106, 1044.2742294105676, 1055.672741450153, 6439.967800160969, 6492.448141907897, 6635.933640663531, 6638.871391863395, 6655.767048153291, 7189.712411503481, 7211.387032102225, 7217.206107328189, 7223.0, 7226.699194145104, 6593.044008618724, 6608.008798680236, 6614.86770264592, 6631.10480085192, 6642.535022548375, 3757.0607348162957, 3806.0104372863257, 3808.5620153682125, 3811.5426148862316, 3813.4089216170983, 7228.891566626505, 7545.962270188724, 7572.432067594952, 7572.697092116254, 7586.203448637924, 6024.1566180734735, 7339.266073392618, 7361.963190184015, 7378.188399276075, 7387.926120738832, 7529.698812047457, 7541.5475071495885, 7562.470627056164, 7563.659635316534, 7571.772846814604, 6899.379055884994, 7178.174509931216, 7192.460565457507, 7194.244604316681, 7200.927990720026, 7039.859202816017, 7073.610951397723, 7100.076989991268, 7108.822279443055, 7119.466040046914, 7480.925190748023, 7536.1333446654335, 7544.6227688616445, 7548.5470871747875, 7557.433192510473, 4563.726176429424, 4566.908661826811, 4567.703099298535, 4578.381918441101, 4590.449146102489, 6891.896621550581, 7181.964090179516, 7184.491256835914, 7184.676689549087, 7192.0, 6929.341712537301, 6932.688029038604, 6943.409810166192, 6943.722251109899, 6949.617771022642, 6849.794506164823, 6909.723611055501, 6915.827104322432, 6920.515563910478, 6927.515073944775, 6753.1896172457655, 6758.189017317954, 6764.053032575345, 6768.221654509597, 6788.321167883172, 6678.699458524281, 6897.379235868796, 6925.930740692528, 6946.596167877805, 6948.235694073749, 6468.967655161882, 6606.933930660824, 6611.33886611335, 6774.729010839511, 6787.93212067873], "unit": "score", "name": "prototype.html", "value": 5068.585536608508}, {"lowerIsBetter": false, "replicates": [660.6597602234853, 678.7217240931219, 688.7864761923804, 781.0822283816516, 821.6918655504186, 14911.627209319759, 15204.315805788738, 15266.618334541628, 15267.770983435243, 15281.0, 11284.492197851096, 14049.367778449969, 14061.578152655411, 14064.367103480343, 14078.155310681366, 905.6196397513028, 923.6997975657904, 926.9768255793625, 929.4748467116092, 932.6316105138466, 739.2348918868971, 749.7938067031555, 920.0477505781517, 920.282179899676, 922.6401703335705, 965.5461932891529, 988.7182153086371, 991.3011327014474, 992.4343124419084, 992.6277645882794, 972.5769290358681, 978.0268632710428, 980.8577756225308, 983.8671779309809, 985.8767654043245, 580.5587753307475, 718.2458418660385, 734.5923012727934, 735.9816004599875, 737.0970561062709, 15012.699746005128, 15014.924925375413, 15056.849431505654, 15077.54767356981, 15134.621634459116, 12446.81329780054, 12461.376931153405, 12491.312977786218, 12516.687082822911, 12614.81077783834, 9665.081817227352, 9780.755481112958, 9805.26460515457, 10014.24893133018, 10022.448765317904, 3292.4732042873266, 3355.5973283205926, 3401.9659803402146, 3422.4866270059442, 3459.41189997701, 71220.57558848795, 71245.0, 71614.64192679003, 71854.2814571858, 72455.55088898193, 9422.717318480456, 9482.241420686329, 9497.192738617157, 9676.90323096774, 9687.515624218902, 22270.331890043326, 22277.0, 22279.220227291968, 22320.553588928455, 22343.88828055882, 7276.527025743309, 7373.631318434057, 7386.0, 7483.775486735407, 7502.6623801929045, 3855.2289542091694, 3999.0, 4001.459802926626, 4018.8794336169963, 4047.9797601012397, 2990.8953186638364, 3454.4990976308295, 3514.578250609943, 3519.085037890167, 3523.5419395478934, 671.4091599392503, 676.2392308652766, 689.1248114894156, 691.0325544238007, 691.7509696509253, 500.32706010415933, 512.423523536022, 528.521687872476, 529.3515443581582, 579.8608333999894, 7163.0, 7273.199948005715, 7539.73610923626, 7717.344025757761, 7801.6879324827505, 491.36123040047795, 545.8144230961412, 557.6709741252599, 581.3430823169791, 598.186466405688, 3388.186835159544, 3410.2667926395447, 3456.637053109387, 3502.1069627244888, 3567.2152126532123, 78836.0, 79147.81278280831, 79362.0, 92379.5381023104, 93113.0688693118, 69877.0, 70028.0, 70559.0, 78602.0, 79465.0, 66853.66573167103, 67085.66457167683, 67189.66405167943, 67282.0, 67949.0, 6586.106009376161, 6722.495812813961, 6742.258351581224, 6876.17485901695, 6988.510804243858, 44675.32987005263, 45264.64206073823, 45364.54635453603, 50893.49106508888, 51016.48983510117, 58941.0, 63578.68210659102, 69928.60142797013, 70143.64928175327, 71053.28946710467, 8994.820103598022, 9002.729918102468, 9223.953880230556, 9237.538123093953, 9688.806223875343, 9841.114299713061, 10294.176465882561, 10338.586456541954, 10493.895061049598, 11390.37352945596, 8834.116588341114, 9316.0, 9822.5088745561, 9887.456189909624, 10063.849042264226, 10040.347377420445, 10225.028622280872, 10255.230857685554, 10284.948575257076, 10343.431111288952, 9792.412455252708, 9868.753281168028, 10043.0, 10051.798964020825, 10054.447005414773, 8433.662653494037, 8449.788755281168, 8558.57207139653, 8645.870311945451, 8722.433041852259, 8460.830783384175, 8498.660053597787, 8512.531810750468, 8586.012608550096, 8621.568921553771], "unit": "score", "name": "yui.html", "value": 6555.903475691312}], "name": "dromaeo_css", "value": 7571.21245372836}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 108.0, 111.0, 109.0, 111.0, 110.0, 117.0, 111.0, 107.0, 111.0], "unit": "score", "name": "ai-astar", "value": 110.4}, {"lowerIsBetter": true, "replicates": [157.0, 100.0, 149.0, 193.0, 147.0, 144.0, 139.0, 117.0, 158.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 143.1}, {"lowerIsBetter": true, "replicates": [186.0, 185.0, 183.0, 187.0, 183.0, 183.0, 185.0, 187.0, 181.0, 178.0], "unit": "score", "name": "audio-dft", "value": 183.8}, {"lowerIsBetter": true, "replicates": [74.0, 77.0, 83.0, 76.0, 56.0, 57.0, 57.0, 80.0, 76.0, 57.0], "unit": "score", "name": "audio-fft", "value": 69.3}, {"lowerIsBetter": true, "replicates": [95.0, 93.0, 128.0, 81.0, 78.0, 83.0, 112.0, 76.0, 109.0, 147.0], "unit": "score", "name": "audio-oscillator", "value": 100.2}, {"lowerIsBetter": true, "replicates": [89.0, 85.0, 88.0, 85.0, 87.0, 85.0, 89.0, 86.0, 88.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.7}, {"lowerIsBetter": true, "replicates": [93.0, 108.0, 127.0, 142.0, 148.0, 93.0, 93.0, 94.0, 93.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 108.3}, {"lowerIsBetter": true, "replicates": [87.0, 87.0, 85.0, 84.0, 87.0, 87.0, 107.0, 143.0, 116.0, 88.0], "unit": "score", "name": "imaging-desaturate", "value": 97.1}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 55.0, 55.0, 55.0, 56.0, 55.0, 66.0, 57.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [64.0, 80.0, 93.0, 113.0, 97.0, 70.0, 69.0, 70.0, 69.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 79.4}, {"lowerIsBetter": true, "replicates": [83.0, 82.0, 83.0, 81.0, 84.0, 78.0, 81.0, 82.0, 84.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.1}, {"lowerIsBetter": true, "replicates": [120.0, 130.0, 125.0, 130.0, 120.0, 132.0, 128.0, 139.0, 110.0, 131.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.5}, {"lowerIsBetter": true, "replicates": [138.0, 138.0, 134.0, 135.0, 143.0, 124.0, 137.0, 137.0, 137.0, 138.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 136.1}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 65.0, 68.0, 69.0, 67.0, 66.0, 68.0, 68.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.0}], "name": "kraken", "value": 1446.2}]} 19:34:39 INFO - Return code: 0 19:34:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:34:39 ERROR - # TBPL SUCCESS # 19:34:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:34:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:34:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:34:39 INFO - 2016-03-15 19:34:39.502 screenresolution[1683:10027] starting screenresolution argv=screenresolution get 19:34:39 INFO - 2016-03-15 19:34:39.505 screenresolution[1683:10027] Display 0: 1600x1200x32@60 19:34:39 INFO - 2016-03-15 19:34:39.511 screenresolution[1684:10030] starting screenresolution argv=screenresolution list 19:34:39 INFO - Available Modes on Display 0 19:34:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:34:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:34:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:34:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:34:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:34:39 INFO - Intel Iris: 19:34:39 INFO - Chipset Model: Intel Iris 19:34:39 INFO - Type: GPU 19:34:39 INFO - Bus: Built-In 19:34:39 INFO - VRAM (Dynamic, Max): 1536 MB 19:34:39 INFO - Vendor: Intel (0x8086) 19:34:39 INFO - Device ID: 0x0a2e 19:34:39 INFO - Revision ID: 0x0009 19:34:39 INFO - Displays: 19:34:39 INFO - Display: 19:34:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:34:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:34:39 INFO - Display Serial Number: 1600x1200 60 19:34:39 INFO - Main Display: Yes 19:34:39 INFO - Mirror: Off 19:34:39 INFO - Online: Yes 19:34:39 INFO - Rotation: Supported 19:34:39 INFO - Return code: 0 19:34:39 INFO - Running post-action listener: _resource_record_post_action 19:34:39 INFO - Running post-run listener: _resource_record_post_run 19:34:40 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 39101952; Write bytes: 704854528; Read time: 469; Write time: 1958 19:34:40 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 380877312; Write bytes: 374822400; Read time: 29117; Write time: 772 19:34:40 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 38147584; Write bytes: 300946432; Read time: 454; Write time: 1127 19:34:40 INFO - Running post-run listener: _upload_blobber_files 19:34:40 INFO - Blob upload gear active. 19:34:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:34:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:34:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:34:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:34:41 INFO - (blobuploader) - INFO - Open directory for files ... 19:34:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:34:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:34:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:34:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:34:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:34:42 INFO - (blobuploader) - INFO - Done attempting. 19:34:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:34:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:34:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:34:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:34:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:34:43 INFO - (blobuploader) - INFO - Done attempting. 19:34:43 INFO - (blobuploader) - INFO - Iteration through files over. 19:34:43 INFO - Return code: 0 19:34:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:34:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:34:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d1a400957935e3986ed641f56237b9c43c120d63ea66db2e219724993ca8fd4ddc0b5d13aa6c7ea68fb7e9ab136e1d4a80bd73b7a44977043b9bda89a4ae13e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d62133a6e1e73643830ab73d1c293261553bf08f29e4559be7efb205063009e670c803721bcb53cc1f940fbb837b5a2ee0f73a108f4a9aec01cb80a02a4d949f"} 19:34:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:34:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:34:43 INFO - Contents: 19:34:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d1a400957935e3986ed641f56237b9c43c120d63ea66db2e219724993ca8fd4ddc0b5d13aa6c7ea68fb7e9ab136e1d4a80bd73b7a44977043b9bda89a4ae13e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d62133a6e1e73643830ab73d1c293261553bf08f29e4559be7efb205063009e670c803721bcb53cc1f940fbb837b5a2ee0f73a108f4a9aec01cb80a02a4d949f"} 19:34:43 INFO - Running post-run listener: copy_logs_to_upload_dir 19:34:43 INFO - Copying logs to upload dir... 19:34:43 INFO - mkdir: /builds/slave/test/build/upload/logs 19:34:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.058883 ========= master_lag: 80.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2016-03-15 19:36:03.454908) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 40 secs) (at 2016-03-15 19:36:03.458264) ========= 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.xwxsBGSoXk/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.JafRscLBbs/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d1a400957935e3986ed641f56237b9c43c120d63ea66db2e219724993ca8fd4ddc0b5d13aa6c7ea68fb7e9ab136e1d4a80bd73b7a44977043b9bda89a4ae13e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d62133a6e1e73643830ab73d1c293261553bf08f29e4559be7efb205063009e670c803721bcb53cc1f940fbb837b5a2ee0f73a108f4a9aec01cb80a02a4d949f"} build_url:https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011826 build_url: 'https://queue.taskcluster.net/v1/task/XVLP-9n7SOmg37G4hOMIHA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d1a400957935e3986ed641f56237b9c43c120d63ea66db2e219724993ca8fd4ddc0b5d13aa6c7ea68fb7e9ab136e1d4a80bd73b7a44977043b9bda89a4ae13e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d62133a6e1e73643830ab73d1c293261553bf08f29e4559be7efb205063009e670c803721bcb53cc1f940fbb837b5a2ee0f73a108f4a9aec01cb80a02a4d949f"}' ========= master_lag: 40.23 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 40 secs) (at 2016-03-15 19:36:43.703516) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2016-03-15 19:36:43.703902) ========= 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.xwxsBGSoXk/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.JafRscLBbs/Listeners TMPDIR=/var/folders/nm/237d458d70v0srksgx6f_qt800000w/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.005732 ========= master_lag: 10.56 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2016-03-15 19:36:54.274572) ========= ========= Started reboot skipped (results: 3, elapsed: 9 secs) (at 2016-03-15 19:36:54.274849) ========= ========= Finished reboot skipped (results: 3, elapsed: 9 secs) (at 2016-03-15 19:37:04.270272) ========= ========= Total master_lag: 131.55 =========