builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0023 starttime: 1454677671.5 results: success (0) buildid: 20160205030204 builduid: 5f6f65fbafa84eea96302f2f95d86a61 revision: 1dbe350b57b17ec1ce2887441b79c6f51b429378 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:51.505331) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:51.506169) ========= ========= Started set props: basedir (results: 0, elapsed: 3 secs) (at 2016-02-05 05:07:51.506928) ========= 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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005534 basedir: '/builds/slave/test' ========= master_lag: 3.63 ========= ========= Finished set props: basedir (results: 0, elapsed: 3 secs) (at 2016-02-05 05:07:55.141490) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:55.141762) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:55.802082) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:55.802331) ========= 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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005261 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:55.858193) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:55.858473) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:55.858795) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:55.859066) ========= 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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-02-05 05:07:55-- 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% 45.1M=0s 2016-02-05 05:07:56 (45.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.166129 ========= master_lag: 0.24 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:56.269796) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:56.270071) ========= 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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.025900 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-05 05:07:56.322185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-02-05 05:07:56.325513) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1dbe350b57b17ec1ce2887441b79c6f51b429378 --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 1dbe350b57b17ec1ce2887441b79c6f51b429378 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KAEzgxrYoN/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.QZ18L1fgTK/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-02-05 05:07:56,402 truncating revision to first 12 chars 2016-02-05 05:07:56,402 Setting DEBUG logging. 2016-02-05 05:07:56,402 attempt 1/10 2016-02-05 05:07:56,402 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1dbe350b57b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-05 05:07:57,315 unpacking tar archive at: mozilla-central-1dbe350b57b1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.159786 ========= master_lag: 3.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-02-05 05:08:00.615574) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-05 05:08:00.615896) ========= echo 1dbe350b57b17ec1ce2887441b79c6f51b429378 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'1dbe350b57b17ec1ce2887441b79c6f51b429378'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KAEzgxrYoN/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.QZ18L1fgTK/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 1dbe350b57b17ec1ce2887441b79c6f51b429378 program finished with exit code 0 elapsedTime=0.005854 script_repo_revision: '1dbe350b57b17ec1ce2887441b79c6f51b429378' ========= master_lag: 0.26 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-05 05:08:00.878875) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 8 secs) (at 2016-02-05 05:08:00.879156) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 8 secs) (at 2016-02-05 05:08:09.147675) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-02-05 05:08:09.147969) ========= /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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:08:09 INFO - MultiFileLogger online at 20160205 05:08:09 in /builds/slave/test 05:08:09 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 05:08:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:08:09 INFO - {'append_to_log': False, 05:08:09 INFO - 'base_work_dir': '/builds/slave/test', 05:08:09 INFO - 'blob_upload_branch': 'Firefox', 05:08:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:08:09 INFO - 'branch': 'Firefox', 05:08:09 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:09 INFO - 'config_files': ('talos/mac_config.py',), 05:08:09 INFO - 'default_actions': ('clobber', 05:08:09 INFO - 'read-buildbot-config', 05:08:09 INFO - 'download-and-extract', 05:08:09 INFO - 'populate-webroot', 05:08:09 INFO - 'create-virtualenv', 05:08:09 INFO - 'install', 05:08:09 INFO - 'run-tests'), 05:08:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:09 INFO - 'download_minidump_stackwalk': True, 05:08:09 INFO - 'download_symbols': 'ondemand', 05:08:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:09 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:09 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:09 INFO - 'installer_path': 'installer.exe', 05:08:09 INFO - 'log_level': 'info', 05:08:09 INFO - 'log_name': 'talos', 05:08:09 INFO - 'log_to_console': True, 05:08:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:08:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:08:09 INFO - 'opt_config_files': (), 05:08:09 INFO - 'pip_index': False, 05:08:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:08:09 INFO - 'cmd': ('bash', 05:08:09 INFO - '-c', 05:08:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:08:09 INFO - 'enabled': True, 05:08:09 INFO - 'halt_on_failure': False, 05:08:09 INFO - 'name': 'check_screen_resolution'},), 05:08:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:08:09 INFO - 'cmd': ('bash', 05:08:09 INFO - '-c', 05:08:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:08:09 INFO - 'enabled': True, 05:08:09 INFO - 'halt_on_failure': False, 05:08:09 INFO - 'name': 'check_screen_resolution'},), 05:08:09 INFO - 'run_cmd_checks_enabled': True, 05:08:09 INFO - 'sps_profile': False, 05:08:09 INFO - 'sps_profile_interval': 0, 05:08:09 INFO - 'suite': 'dromaeojs', 05:08:09 INFO - 'system_bits': '32', 05:08:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:09 INFO - 'title': 't-yosemite-r7-0023', 05:08:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:09 INFO - 'use_talos_json': True, 05:08:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:08:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:09 INFO - 'work_dir': 'build'} 05:08:09 INFO - ##### 05:08:09 INFO - ##### Running clobber step. 05:08:09 INFO - ##### 05:08:09 INFO - Running pre-action listener: _resource_record_pre_action 05:08:09 INFO - Running main action method: clobber 05:08:09 INFO - rmtree: /builds/slave/test/build 05:08:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:08:11 INFO - Running post-action listener: _resource_record_post_action 05:08:11 INFO - ##### 05:08:11 INFO - ##### Running read-buildbot-config step. 05:08:11 INFO - ##### 05:08:11 INFO - Running pre-action listener: _resource_record_pre_action 05:08:11 INFO - Running main action method: read_buildbot_config 05:08:11 INFO - Using buildbot properties: 05:08:11 INFO - { 05:08:11 INFO - "project": "", 05:08:11 INFO - "product": "firefox", 05:08:11 INFO - "script_repo_revision": "production", 05:08:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:08:11 INFO - "repository": "", 05:08:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:08:11 INFO - "buildid": "20160205030204", 05:08:11 INFO - "stage_platform": "macosx64", 05:08:11 INFO - "basedir": "/builds/slave/test", 05:08:11 INFO - "nightly_build": "True", 05:08:11 INFO - "buildnumber": 139, 05:08:11 INFO - "slavename": "t-yosemite-r7-0023", 05:08:11 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:08:11 INFO - "platform": "yosemite_r7", 05:08:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:08:11 INFO - "branch": "mozilla-central", 05:08:11 INFO - "repo_path": "mozilla-central", 05:08:11 INFO - "revision": "1dbe350b57b17ec1ce2887441b79c6f51b429378", 05:08:11 INFO - "pgo_build": "False", 05:08:11 INFO - "builduid": "5f6f65fbafa84eea96302f2f95d86a61", 05:08:11 INFO - "slavebuilddir": "test" 05:08:11 INFO - } 05:08:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 05:08:11 INFO - Running post-action listener: _resource_record_post_action 05:08:11 INFO - ##### 05:08:11 INFO - ##### Running download-and-extract step. 05:08:11 INFO - ##### 05:08:11 INFO - Running pre-action listener: _resource_record_pre_action 05:08:11 INFO - Running main action method: download_and_extract 05:08:11 INFO - mkdir: /builds/slave/test/build/tests 05:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:11 INFO - https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:08:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/test_packages.json 05:08:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/test_packages.json 05:08:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:08:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:08:17 INFO - Downloaded 1277 bytes. 05:08:17 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:08:17 INFO - Using the following test package requirements: 05:08:17 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:08:17 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:08:17 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 05:08:17 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:08:17 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 05:08:17 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:08:17 INFO - u'jsshell-mac.zip'], 05:08:17 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:08:17 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 05:08:17 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:08:17 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:08:17 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 05:08:17 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:08:17 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 05:08:17 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:08:17 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 05:08:17 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:08:17 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:08:17 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 05:08:17 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:08: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')]} 05:08:17 INFO - https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:08:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:08:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:08:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:08:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:08:33 INFO - Downloaded 17748972 bytes. 05:08:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:08:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:08:36 INFO - Return code: 0 05:08:36 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:36 INFO - https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:08:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:08:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:08:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:08:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:08:41 INFO - Downloaded 17748972 bytes. 05:08:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:08:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:08:45 INFO - Return code: 0 05:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:45 INFO - https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:08:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:08:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:08:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:08:45 INFO - Downloaded 10706547 bytes. 05:08:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:08:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:08:46 INFO - Return code: 0 05:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:46 INFO - https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:08:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:08:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:08:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 05:08:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 05:08:50 INFO - Downloaded 111958175 bytes. 05:08:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:08:50 INFO - mkdir: /builds/slave/test/properties 05:08:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:08:50 INFO - Writing to file /builds/slave/test/properties/build_url 05:08:50 INFO - Contents: 05:08:50 INFO - build_url:https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 05:08:51 INFO - Running post-action listener: _resource_record_post_action 05:08:51 INFO - Running post-action listener: set_extra_try_arguments 05:08:51 INFO - ##### 05:08:51 INFO - ##### Running populate-webroot step. 05:08:51 INFO - ##### 05:08:51 INFO - Running pre-action listener: _resource_record_pre_action 05:08:51 INFO - Running main action method: populate_webroot 05:08:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:08:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:08:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:08:51 INFO - '--tppagecycles', 05:08:51 INFO - '7'], 05:08:51 INFO - 'tests': ['tsvgm']}}, 05:08:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:08:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:08:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:08:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:08:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:08:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:08:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:51 INFO - 'talos_options': ['--e10s'], 05:08:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:51 INFO - 'tests': ['damp', 'tps']}, 05:08:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:51 INFO - 'talos_options': ['--e10s'], 05:08:51 INFO - 'tests': ['damp', 'tps']}, 05:08:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:08:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:08:51 INFO - 'other': {'tests': ['a11yr', 05:08:51 INFO - 'ts_paint', 05:08:51 INFO - 'tpaint', 05:08:51 INFO - 'sessionrestore', 05:08:51 INFO - 'sessionrestore_no_auto_restore']}, 05:08:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:08:51 INFO - 'tests': ['a11yr', 05:08:51 INFO - 'ts_paint', 05:08:51 INFO - 'tpaint', 05:08:51 INFO - 'sessionrestore', 05:08:51 INFO - 'sessionrestore_no_auto_restore']}, 05:08:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:08:51 INFO - 'tests': ['a11yr', 05:08:51 INFO - 'ts_paint', 05:08:51 INFO - 'tpaint', 05:08:51 INFO - 'sessionrestore', 05:08:51 INFO - 'sessionrestore_no_auto_restore']}, 05:08:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:08:51 INFO - 'tests': ['a11yr', 05:08:51 INFO - 'ts_paint', 05:08:51 INFO - 'tpaint', 05:08:51 INFO - 'sessionrestore', 05:08:51 INFO - 'sessionrestore_no_auto_restore']}, 05:08:51 INFO - 'other_l64': {'tests': ['a11yr', 05:08:51 INFO - 'ts_paint', 05:08:51 INFO - 'tpaint', 05:08:51 INFO - 'sessionrestore', 05:08:51 INFO - 'sessionrestore_no_auto_restore']}, 05:08:51 INFO - 'other_nol64': {'tests': ['a11yr', 05:08:51 INFO - 'ts_paint', 05:08:51 INFO - 'tpaint', 05:08:51 INFO - 'sessionrestore', 05:08:51 INFO - 'sessionrestore_no_auto_restore']}, 05:08:51 INFO - 'svgr': {'tests': ['tsvgx', 05:08:51 INFO - 'tsvgr_opacity', 05:08:51 INFO - 'tart', 05:08:51 INFO - 'tscrollx', 05:08:51 INFO - 'cart']}, 05:08:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:08:51 INFO - 'tests': ['tsvgx', 05:08:51 INFO - 'tsvgr_opacity', 05:08:51 INFO - 'tart', 05:08:51 INFO - 'tscrollx', 05:08:51 INFO - 'cart']}, 05:08:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:51 INFO - 'tests': ['tp5o']}, 05:08:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:51 INFO - 'talos_options': ['--e10s'], 05:08:51 INFO - 'tests': ['tp5o']}, 05:08:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:51 INFO - 'talos_options': ['--xperf_path', 05:08:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:51 INFO - 'tests': ['tp5n']}, 05:08:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:51 INFO - 'talos_options': ['--e10s', 05:08:51 INFO - '--xperf_path', 05:08:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:51 INFO - 'tests': ['tp5n']}}, 05:08:51 INFO - 'talos.zip': {'path': '', 05:08:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:08:51 INFO - Running post-action listener: _resource_record_post_action 05:08:51 INFO - ##### 05:08:51 INFO - ##### Running create-virtualenv step. 05:08:51 INFO - ##### 05:08:51 INFO - Running pre-action listener: _resource_record_pre_action 05:08:51 INFO - Running main action method: create_virtualenv 05:08:51 INFO - Creating virtualenv /builds/slave/test/build/venv 05:08:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:08:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:08:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:08:51 INFO - Using real prefix '/tools/python27' 05:08:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:08:51 INFO - Installing distribute.............................................................................................................................................................................................done. 05:08:54 INFO - Installing pip.................done. 05:08:54 INFO - Return code: 0 05:08:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:54 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 0x103200a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032074c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae6a584670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, '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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:54 INFO - 'HOME': '/Users/cltbld', 05:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:54 INFO - 'LOGNAME': 'cltbld', 05:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:54 INFO - 'MOZ_NO_REMOTE': '1', 05:08:54 INFO - 'NO_EM_RESTART': '1', 05:08:54 INFO - 'PAGER': '/bin/cat', 05:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:54 INFO - 'PWD': '/builds/slave/test', 05:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:54 INFO - 'SHELL': '/bin/bash', 05:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:08:54 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:54 INFO - 'USER': 'cltbld', 05:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:54 INFO - 'XPC_FLAGS': '0x0', 05:08:54 INFO - 'XPC_SERVICE_NAME': '0', 05:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:08:55 INFO - Cleaning up... 05:08:55 INFO - Return code: 0 05:08:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:55 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 0x103200a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032074c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae6a584670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, '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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:55 INFO - 'HOME': '/Users/cltbld', 05:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:55 INFO - 'LOGNAME': 'cltbld', 05:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:55 INFO - 'MOZ_NO_REMOTE': '1', 05:08:55 INFO - 'NO_EM_RESTART': '1', 05:08:55 INFO - 'PAGER': '/bin/cat', 05:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:55 INFO - 'PWD': '/builds/slave/test', 05:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:55 INFO - 'SHELL': '/bin/bash', 05:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:08:55 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:55 INFO - 'USER': 'cltbld', 05:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:55 INFO - 'XPC_FLAGS': '0x0', 05:08:55 INFO - 'XPC_SERVICE_NAME': '0', 05:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:55 INFO - Downloading/unpacking psutil>=0.7.1 05:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:08:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:58 INFO - Installing collected packages: psutil 05:08:58 INFO - Running setup.py install for psutil 05:08:58 INFO - building 'psutil._psutil_osx' extension 05:08:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:08:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:08:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:08:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:08:59 INFO - building 'psutil._psutil_posix' extension 05:08:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:08:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:08:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:08:59 INFO - ^ 05:08:59 INFO - 1 warning generated. 05:08:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:08:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:59 INFO - Successfully installed psutil 05:08:59 INFO - Cleaning up... 05:08:59 INFO - Return code: 0 05:08:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:59 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 0x103200a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032074c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae6a584670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, '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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:59 INFO - 'HOME': '/Users/cltbld', 05:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:59 INFO - 'LOGNAME': 'cltbld', 05:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:59 INFO - 'MOZ_NO_REMOTE': '1', 05:08:59 INFO - 'NO_EM_RESTART': '1', 05:08:59 INFO - 'PAGER': '/bin/cat', 05:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:59 INFO - 'PWD': '/builds/slave/test', 05:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:59 INFO - 'SHELL': '/bin/bash', 05:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:08:59 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:59 INFO - 'USER': 'cltbld', 05:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:59 INFO - 'XPC_FLAGS': '0x0', 05:08:59 INFO - 'XPC_SERVICE_NAME': '0', 05:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:09:02 INFO - Installing collected packages: mozsystemmonitor 05:09:02 INFO - Running setup.py install for mozsystemmonitor 05:09:02 INFO - Successfully installed mozsystemmonitor 05:09:02 INFO - Cleaning up... 05:09:02 INFO - Return code: 0 05:09:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:02 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 0x103200a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032074c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae6a584670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, '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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:02 INFO - 'HOME': '/Users/cltbld', 05:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:02 INFO - 'LOGNAME': 'cltbld', 05:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:02 INFO - 'MOZ_NO_REMOTE': '1', 05:09:02 INFO - 'NO_EM_RESTART': '1', 05:09:02 INFO - 'PAGER': '/bin/cat', 05:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:02 INFO - 'PWD': '/builds/slave/test', 05:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:02 INFO - 'SHELL': '/bin/bash', 05:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:09:02 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:02 INFO - 'USER': 'cltbld', 05:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:02 INFO - 'XPC_FLAGS': '0x0', 05:09:02 INFO - 'XPC_SERVICE_NAME': '0', 05:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09: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 05:09:05 INFO - Downloading docopt-0.6.1.tar.gz 05:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:06 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:06 INFO - Running setup.py install for blobuploader 05:09:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:06 INFO - Running setup.py install for requests 05:09:06 INFO - Running setup.py install for docopt 05:09:06 INFO - Successfully installed blobuploader requests docopt 05:09:06 INFO - Cleaning up... 05:09:06 INFO - Return code: 0 05:09:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:06 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 0x103200a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032074c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae6a584670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, '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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:06 INFO - 'HOME': '/Users/cltbld', 05:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:06 INFO - 'LOGNAME': 'cltbld', 05:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:06 INFO - 'MOZ_NO_REMOTE': '1', 05:09:06 INFO - 'NO_EM_RESTART': '1', 05:09:06 INFO - 'PAGER': '/bin/cat', 05:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:06 INFO - 'PWD': '/builds/slave/test', 05:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:06 INFO - 'SHELL': '/bin/bash', 05:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:09:06 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:06 INFO - 'USER': 'cltbld', 05:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:06 INFO - 'XPC_FLAGS': '0x0', 05:09:06 INFO - 'XPC_SERVICE_NAME': '0', 05:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:07 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-d_nWd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:07 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-PCN_Zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:07 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-jyDVLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:07 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-REyaZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:07 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-UVquva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:07 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-YetkgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:07 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-pXdQn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:07 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-77o_tU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:08 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-fAylrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:08 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-I8YYrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:08 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-VF6y3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:08 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-YY8Y4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:08 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-7KPsqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:08 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-_9d9nI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:08 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-uP9Sxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:08 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-z3yfxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:08 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5cSxaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:08 INFO - Running setup.py install for manifestparser 05:09:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:09 INFO - Running setup.py install for mozcrash 05:09:09 INFO - Running setup.py install for mozdebug 05:09:09 INFO - Running setup.py install for mozdevice 05:09:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:09 INFO - Running setup.py install for mozfile 05:09:09 INFO - Running setup.py install for mozhttpd 05:09:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:09 INFO - Running setup.py install for mozinfo 05:09:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Running setup.py install for mozInstall 05:09:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Running setup.py install for mozleak 05:09:10 INFO - Running setup.py install for mozlog 05:09:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Running setup.py install for moznetwork 05:09:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Running setup.py install for mozprocess 05:09:10 INFO - Running setup.py install for mozprofile 05:09:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:10 INFO - Running setup.py install for mozrunner 05:09:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:11 INFO - Running setup.py install for mozscreenshot 05:09:11 INFO - Running setup.py install for moztest 05:09:11 INFO - Running setup.py install for mozversion 05:09:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:11 INFO - Cleaning up... 05:09:11 INFO - Return code: 0 05:09:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:11 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 0x103200a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032074c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae6a584670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, '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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:11 INFO - 'HOME': '/Users/cltbld', 05:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:11 INFO - 'LOGNAME': 'cltbld', 05:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:11 INFO - 'MOZ_NO_REMOTE': '1', 05:09:11 INFO - 'NO_EM_RESTART': '1', 05:09:11 INFO - 'PAGER': '/bin/cat', 05:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:11 INFO - 'PWD': '/builds/slave/test', 05:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:11 INFO - 'SHELL': '/bin/bash', 05:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:09:11 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:11 INFO - 'USER': 'cltbld', 05:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:11 INFO - 'XPC_FLAGS': '0x0', 05:09:11 INFO - 'XPC_SERVICE_NAME': '0', 05:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:11 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-7Zj14A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:11 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-unmGob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-oDLknv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Cx7B9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-642LmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-OI85YR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-tDNz17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-hj8uy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-xVv65B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-53h_oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-YvPy_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:12 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-cLYFoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-_6tZod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-KQr6PN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-kVGtwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-wtdzmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:13 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-ZdM68r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:09:13 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:13 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:16 INFO - Downloading blessings-1.6.tar.gz 05:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:16 INFO - Installing collected packages: blessings 05:09:16 INFO - Running setup.py install for blessings 05:09:16 INFO - Successfully installed blessings 05:09:16 INFO - Cleaning up... 05:09:16 INFO - Return code: 0 05:09:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:16 INFO - Reading from file tmpfile_stdout 05:09:16 INFO - Current package versions: 05:09:16 INFO - blessings == 1.6 05:09:16 INFO - blobuploader == 1.2.4 05:09:16 INFO - docopt == 0.6.1 05:09:16 INFO - manifestparser == 1.1 05:09:16 INFO - mozInstall == 1.12 05:09:16 INFO - mozcrash == 0.16 05:09:16 INFO - mozdebug == 0.1 05:09:16 INFO - mozdevice == 0.48 05:09:16 INFO - mozfile == 1.2 05:09:16 INFO - mozhttpd == 0.7 05:09:16 INFO - mozinfo == 0.9 05:09:16 INFO - mozleak == 0.1 05:09:16 INFO - mozlog == 3.1 05:09:16 INFO - moznetwork == 0.27 05:09:16 INFO - mozprocess == 0.22 05:09:16 INFO - mozprofile == 0.28 05:09:16 INFO - mozrunner == 6.11 05:09:16 INFO - mozscreenshot == 0.1 05:09:16 INFO - mozsystemmonitor == 0.0 05:09:16 INFO - moztest == 0.7 05:09:16 INFO - mozversion == 1.4 05:09:16 INFO - psutil == 3.1.1 05:09:16 INFO - requests == 1.2.3 05:09:16 INFO - wsgiref == 0.1.2 05:09:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:16 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 0x103200a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032074c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae6a584670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, '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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:16 INFO - 'HOME': '/Users/cltbld', 05:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:16 INFO - 'LOGNAME': 'cltbld', 05:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:16 INFO - 'MOZ_NO_REMOTE': '1', 05:09:16 INFO - 'NO_EM_RESTART': '1', 05:09:16 INFO - 'PAGER': '/bin/cat', 05:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:16 INFO - 'PWD': '/builds/slave/test', 05:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:16 INFO - 'SHELL': '/bin/bash', 05:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:09:16 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:16 INFO - 'USER': 'cltbld', 05:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:16 INFO - 'XPC_FLAGS': '0x0', 05:09:16 INFO - 'XPC_SERVICE_NAME': '0', 05:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:09:17 INFO - Cleaning up... 05:09:17 INFO - Return code: 0 05:09:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:17 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 0x103200a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032074c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae6a584670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, '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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:17 INFO - 'HOME': '/Users/cltbld', 05:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:17 INFO - 'LOGNAME': 'cltbld', 05:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:17 INFO - 'MOZ_NO_REMOTE': '1', 05:09:17 INFO - 'NO_EM_RESTART': '1', 05:09:17 INFO - 'PAGER': '/bin/cat', 05:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:17 INFO - 'PWD': '/builds/slave/test', 05:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:17 INFO - 'SHELL': '/bin/bash', 05:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:09:17 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:17 INFO - 'USER': 'cltbld', 05:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:17 INFO - 'XPC_FLAGS': '0x0', 05:09:17 INFO - 'XPC_SERVICE_NAME': '0', 05:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:17 INFO - Downloading/unpacking jsonschema 05:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:19 INFO - Downloading/unpacking functools32 (from jsonschema) 05:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:20 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:20 INFO - warning: no files found matching '*.txt' 05:09:20 INFO - no previously-included directories found matching 'build' 05:09:20 INFO - no previously-included directories found matching 'dist' 05:09:20 INFO - no previously-included directories found matching '.git*' 05:09:20 INFO - Installing collected packages: jsonschema, functools32 05:09:20 INFO - Running setup.py install for functools32 05:09:20 INFO - warning: no files found matching '*.txt' 05:09:20 INFO - no previously-included directories found matching 'build' 05:09:20 INFO - no previously-included directories found matching 'dist' 05:09:20 INFO - no previously-included directories found matching '.git*' 05:09:20 INFO - Successfully installed jsonschema functools32 05:09:20 INFO - Cleaning up... 05:09:20 INFO - Return code: 0 05:09:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:20 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 0x103200a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032074c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae6a584670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, '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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:09:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:09:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:20 INFO - 'HOME': '/Users/cltbld', 05:09:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:20 INFO - 'LOGNAME': 'cltbld', 05:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:20 INFO - 'MOZ_NO_REMOTE': '1', 05:09:20 INFO - 'NO_EM_RESTART': '1', 05:09:20 INFO - 'PAGER': '/bin/cat', 05:09:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:20 INFO - 'PWD': '/builds/slave/test', 05:09:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:20 INFO - 'SHELL': '/bin/bash', 05:09:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:09:20 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:09:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:20 INFO - 'USER': 'cltbld', 05:09:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:20 INFO - 'XPC_FLAGS': '0x0', 05:09:20 INFO - 'XPC_SERVICE_NAME': '0', 05:09:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:20 INFO - Downloading/unpacking flake8 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:09:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:09:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:09:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:24 INFO - no previously-included directories found matching 'docs/_build' 05:09:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:09:24 INFO - Running setup.py install for pep8 05:09:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:24 INFO - no previously-included directories found matching 'docs/_build' 05:09:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:09:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:09:24 INFO - Cleaning up... 05:09:24 INFO - Return code: 0 05:09:24 INFO - Running post-action listener: _resource_record_post_action 05:09:24 INFO - Running post-action listener: _start_resource_monitoring 05:09:24 INFO - Starting resource monitoring. 05:09:24 INFO - ##### 05:09:24 INFO - ##### Running install step. 05:09:24 INFO - ##### 05:09:24 INFO - Running pre-action listener: _resource_record_pre_action 05:09:24 INFO - Running main action method: install 05:09:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:24 INFO - Reading from file tmpfile_stdout 05:09:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:24 INFO - Reading from file tmpfile_stdout 05:09:24 INFO - Output received: 05:09:24 INFO - Usage: mozinstall [options] installer 05:09:24 INFO - Options: 05:09:24 INFO - -h, --help show this help message and exit 05:09:24 INFO - -d DEST, --destination=DEST 05:09:24 INFO - Directory to install application into. [default: 05:09:24 INFO - "/builds/slave/test"] 05:09:24 INFO - --app=APP Application being installed. [default: firefox] 05:09:24 INFO - mkdir: /builds/slave/test/build/application 05:09:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:09:59 INFO - Reading from file tmpfile_stdout 05:09:59 INFO - Output received: 05:09:59 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:09:59 INFO - Running post-action listener: _resource_record_post_action 05:09:59 INFO - ##### 05:09:59 INFO - ##### Running run-tests step. 05:09:59 INFO - ##### 05:09:59 INFO - Running pre-action listener: _resource_record_pre_action 05:09:59 INFO - Running main action method: run_tests 05:09:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:09:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:09:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:09:59 INFO - 2016-02-05 05:09:59.110 screenresolution[1653:7595] starting screenresolution argv=screenresolution get 05:09:59 INFO - 2016-02-05 05:09:59.113 screenresolution[1653:7595] Display 0: 1600x1200x32@60 05:09:59 INFO - 2016-02-05 05:09:59.118 screenresolution[1654:7597] starting screenresolution argv=screenresolution list 05:09:59 INFO - Available Modes on Display 0 05:09:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:09:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:09:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:09:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:09:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:09:59 INFO - Intel Iris: 05:09:59 INFO - Chipset Model: Intel Iris 05:09:59 INFO - Type: GPU 05:09:59 INFO - Bus: Built-In 05:09:59 INFO - VRAM (Dynamic, Max): 1536 MB 05:09:59 INFO - Vendor: Intel (0x8086) 05:09:59 INFO - Device ID: 0x0a2e 05:09:59 INFO - Revision ID: 0x0009 05:09:59 INFO - Displays: 05:09:59 INFO - Display: 05:09:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:09:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:09:59 INFO - Display Serial Number: 1600x1200 60 05:09:59 INFO - Main Display: Yes 05:09:59 INFO - Mirror: Off 05:09:59 INFO - Online: Yes 05:09:59 INFO - Rotation: Supported 05:09:59 INFO - Return code: 0 05:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:59 INFO - Python 2.7.3 05:09:59 INFO - Return code: 0 05:09:59 INFO - grabbing minidump binary from tooltool 05:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:59 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030a5d30>, '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 0x102ec8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10309bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:09:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:09:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:59 INFO - Return code: 0 05:09:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:09:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:09:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:10:00 INFO - Return code: 0 05:10:00 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:10:00 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAEzgxrYoN/Render', 05:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:00 INFO - 'HOME': '/Users/cltbld', 05:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:00 INFO - 'LOGNAME': 'cltbld', 05:10:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:10:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:00 INFO - 'MOZ_NO_REMOTE': '1', 05:10:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:10:00 INFO - 'NO_EM_RESTART': '1', 05:10:00 INFO - 'PAGER': '/bin/cat', 05:10:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:00 INFO - 'PWD': '/builds/slave/test', 05:10:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:00 INFO - 'SHELL': '/bin/bash', 05:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZ18L1fgTK/Listeners', 05:10:00 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:00 INFO - 'USER': 'cltbld', 05:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:00 INFO - 'XPC_FLAGS': '0x0', 05:10:00 INFO - 'XPC_SERVICE_NAME': '0', 05:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:00 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:10:00 INFO - mozversion application_buildid: 20160205030204 05:10:00 INFO - mozversion application_changeset: 1dbe350b57b17ec1ce2887441b79c6f51b429378 05:10:00 INFO - mozversion application_display_name: Nightly 05:10:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:10:00 INFO - mozversion application_name: Firefox 05:10:00 INFO - mozversion application_remotingname: firefox 05:10:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:10:00 INFO - mozversion application_vendor: Mozilla 05:10:00 INFO - mozversion application_version: 47.0a1 05:10:00 INFO - mozversion platform_buildid: 20160205030204 05:10:00 INFO - mozversion platform_changeset: 1dbe350b57b17ec1ce2887441b79c6f51b429378 05:10:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:10:00 INFO - mozversion platform_version: 47.0a1 05:10:00 INFO - using testdate: 1454677800 05:10:00 INFO - actual date: 1454677800 05:10:00 INFO - starting webserver on 'localhost:49284' 05:10:00 INFO - SUITE-START | Running 2 tests 05:10:00 INFO - TEST-START | dromaeo_css 05:10:00 INFO - operating with platform_type : mac_ 05:10:00 INFO - Initialising browser for dromaeo_css test... 05:10:00 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpaGpWPu/profile http://localhost:49284/getInfo.html) 05:10:01 INFO - PROCESS | 1672 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 05:10:03 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 05:10:03 INFO - PROCESS | 1672 | colorDepth:24 05:10:03 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 05:10:03 INFO - PROCESS | 1672 | __metrics 05:10:03 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:10:03 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:10:04 INFO - TEST-INFO | 1672: exit 0 05:10:04 INFO - Browser initialized. 05:10:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:04 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpaGpWPu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:16 INFO - PROCESS | 1676 | RSS: Main: 246247424 05:10:16 INFO - PROCESS | 1676 | 05:11:07 INFO - PROCESS | 1676 | 1454677867055 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:13:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49284/tests/dromaeo/cssquery-ext.html) 05:13:19 INFO - PROCESS | 1676 | RSS: Main: 387727360 05:13:19 INFO - PROCESS | 1676 | 05:16:22 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-ext.html (next: http://localhost:49284/tests/dromaeo/cssquery-jquery.html) 05:16:23 INFO - PROCESS | 1676 | RSS: Main: 385417216 05:16:23 INFO - PROCESS | 1676 | 05:19:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49284/tests/dromaeo/cssquery-mootools.html) 05:19:25 INFO - PROCESS | 1676 | RSS: Main: 382099456 05:19:25 INFO - PROCESS | 1676 | 05:22:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49284/tests/dromaeo/cssquery-prototype.html) 05:22:28 INFO - PROCESS | 1676 | RSS: Main: 355409920 05:22:28 INFO - PROCESS | 1676 | 05:25:31 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49284/tests/dromaeo/cssquery-yui.html) 05:25:32 INFO - PROCESS | 1676 | RSS: Main: 354889728 05:25:32 INFO - PROCESS | 1676 | 05:28:35 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-yui.html (next: http://localhost:49284/tests/dromaeo/cssquery-dojo.html) 05:28:35 INFO - PROCESS | 1676 | RSS: Main: 427372544 05:28:35 INFO - PROCESS | 1676 | 05:28:35 INFO - PROCESS | 1676 | __start_tp_report 05:28:35 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 05:28:35 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 05:28:35 INFO - PROCESS | 1676 | |i|pagename|runs| 05:28:35 INFO - PROCESS | 1676 | |0;dojo.html;3428.4171690812564;3459.4118999770035;3494.5107684924105;3498.9300213995707;3535.9823200883993;5612.242347283115;5688.943110568893;5690.402507736685;5717.714114294285;5740.368559458456;5654.830355089354;5718.228039214715;5729.828105156842;5740.138979153129;5746.109353050273;5042.268871013701;5109.974450127745;5134.204198349262;5172.327597412331;5222.9477705223035;5225.921611175816;5226.503482169188;5234.973825130888;5235.240890070937;5236;5196.350456192976;5202.089634313998;5204.297419848329;5207.609429292799;5208.010478009185;5195.194744814522;5203.427622961472;5209.453007434203;5218.973905130469;5221.582273418117;4589.33454649074;5139.717315547643;5243.213517972296;5257.553107985826;5261.316028916254;5646.943530564683;5694.4590263924865;5694.77220911163;5752.0796672532615;5759;6200.162977997958;6221.253449586078;6240.282367527701;6264.060390941349;6275.121482992385;6116.609690437446;6136.539759518054;6185.56701030928;6201.255849298068;6316.652584107872;5769.740361683735;5799.507041901401;5813.157092221604;5833.270841144857;5862.38444963277;6231.906521402183;6273.215848018997;6285.685715714196;6301.180846489927;6314.02132669437;6004.909926351108;6113.052476866004;6119.816405507842;6123.203983482208;6125.84685382869;6095.786647467317;6157.922363586355;6158.507319414436;6209.658468784261;6217.875642487215;6133.202683651097;6177.135201071854;6250.74997000124;6253.78111766086;6255.530835187288;6226.470749986212;6227.906581401283;6229.53278504114;6240.126382306481;6240.4071613196675;4804.99095190014;4867.805287788448;4871.585915197177;4880;4911.8280860169725;4374.803133858984;4376.277914144131;4391.802368893407;4393.582609652078;4435.3125265583885;5596.216529685766;5644.435556444323;5648.519875810603;5650.717464126859;5655.1799989001365;6219.346968568326;6220.098085777628;6247.125402443661;6263.812085637438;6320;5665.263515742927;5675.091985282335;5688.63023903445;5691.402402747734;5742.3396309424115;5363.249145119687;5363.544098751572;5368.489993450616;5368.5436737876935;5371.543418809445;6219.657918814418;6238.625682459067;6251.343608920998;6266;6276.717547710456;6288.874222515616;6296.181496405621;6297.4647154992335;6309.2744334400295;6322.683865806784;6288.8427789303805;6292;6300.7479700811455;6308.463780578702;6308.589941653778;6032.75868965254;6095.256985594852;6110.680268050674;6114.145302971391;6206.689665516616;5257.973710131425;5921.555883308855;6311.96844015777;6313.494920406265;6320.209973753281;6085.66528840918;6106.877862442638;6110.26676798787;6111.572189946839;6112.541559382975;5945.34601193877;6002.309734380602;6010.849728756816;6050.42520960508;6115.541334400027;5872.354041055395;5926.170336153028;5933;5948.464638182584;5955.821325360246;6015.0375939851165;6091.42131497507;6103.908441373294;6113.327533971171;6120.326764056039;5751.194832723339;5780.971095144666;5850.502707269759;5925;6027.457528822427 05:28:35 INFO - PROCESS | 1676 | |1;ext.html;459.3224993135126;467.13579877227113;480.9275316045217;580.564576567574;616.2851092732429;28217.576736348972;30365;38168;38994.83015509532;39208;41821.79089104558;42212.57787422125;42388.364174537404;42528.362074568904;42539.93650158748;9505.097015783489;9748.073932976356;9753.95123024384;9754.46350450725;9767.120959113678;9458.81082378352;9598.76003099921;9610.711678649617;9612.134907858292;9625.663101791439;9693.127618514332;9769.609215631403;9774.755631109243;9779;9789.363691360075;9688.273379496532;9769.609215631366;9774.071463210983;9778.315517913748;9788.999999999964;9566.47384393858;9688.370255933341;9691.321607487478;9714.465704386255;9741.025897410273;40115.39826902599;40429.19141617151;40443.39334909979;40531.189376212606;40545.59454405477;3272.427325218101;3454.101933497309;3494.72042236623;3639.7452178347526;3790.58303586608;28630.85684571564;28880.85559572189;29261.610758386414;29279.70720292791;29391.41217175666;2773.6394268745134;2822.7741780657702;2868.225579093633;2869.58391033299;2879.6976317486574;373331;373812.1309393463;374016;374367;375342;56395;57422.425775742544;57480.42519574834;63437.68281158657;65542.68914621681;60932.08601870976;61324;61579.38420615737;61606.383936160964;61697.76604467886;16673.583160421087;16879.902806317794;16886.662266754844;16897.239624216938;16921.238544265536;11496.54013839437;11706.765864682659;11739;12691;12755.489780408865;18536.81463185378;18636.068196590117;20481.344047625018;20484.97575121238;20577.69133462999;7773.5724535149975;7913.881291780628;7927.679414718877;7939;7952.3638108951145;958.9760255993516;973.5764942250144;985.7979114281555;985.8669079674296;1005.2711783956573;26193.73806261951;29381.706182938324;29602.407951840843;29818.55272170919;30463.695363046085;1042.5412818359898;1065.984010239847;1101.515333253366;1129.5877004893155;1134.2627292260097;14317.6420589484;14422.765119956888;14474.710505790035;14675.972681912377;14824.110553366832;545003.5499644952;551702;553787;554136;556809;47091;49059.01881962341;56065.878682426126;57052.71473642605;57351;1400.3278426355407;1402.7404930087966;1440.9927950360182;1441.0849110814709;1442.9711405772036;23986.760132398453;26312.605310920735;27943.441131176856;28085.017024404864;28767.712322876505;50961.7451912738;51377.972440551726;51382.74308628433;51516.48483515117;51531.90808551499;358432;360016;360634;360797;363119;29598.408031839674;29865.25336866509;30182.09453716392;30428.39143217079;30434.086977390714;41862.372064419666;43709.34435983526;44523.33215001713;44536.331955020054;45686.54313456823;35406.93779186629;36536.634633653324;37299.25401491901;37376.439353410264;37426.25147497089;32650.204238767095;33432.49851252282;36646.816765917065;37216.88349349524;37346.626533734314;36340.09149771277;36952.630473695994;36960.63039369572;37230.06924826901;37241;30637.387252255277;32335.676643233266;32461.513077303385;32493.28847317173;33152;24060.15789447361;24503.142390016263;24556.877215613807;24786.92720283542;24793.752062479143 05:28:35 INFO - PROCESS | 1676 | |2;jquery.html;362.23387535362707;366.2071614953627;368.1771241275749;370.7034372501998;374.01447186663137;12396.442160102795;15471.303791329388;18805.529861753446;19438.222471101155;20060.498487537818;10048.748781280472;11685.474153663085;12031.759364812699;18796.24815007398;19039.809601903977;5057.342545469084;5228.92156617651;5232.895342093156;5301.045811753883;5301.3373328333955;3802.315583195024;3997.000749812547;4005.83976640934;5529.115341545354;5777.451142141511;4750.76246187691;5658.7170641467965;5735.512481439062;5757.510611598019;5769.3653698093385;5729.713514324309;5731.083026715726;5739.081746920494;5739.196512488255;5741.368449470555;5340.279062326575;5404;5446.537044351235;5456.427075157091;5589.217509548666;12955.48178072876;13058.608241752763;21932.561348772935;23506.529869402515;24290.271291861274;1822.7903791064066;2108.388567315477;2262.6832243485924;2281.3042022183226;2779.3051737065734;9771.657991970318;9877.60489580416;9889.307748457612;9891.604335826558;9911.571664942136;1731.3593970231095;1829.5060333709823;1836.8255015773614;1849.6948003579394;1861.4601765488128;87735;90255;90662.09337906602;90915.54542227247;92532.07467925301;10773.24587278883;10976.614994180021;10986.670399888017;11000.449977501094;11028.889711102785;13190;13246.470141194244;13270.071095023255;13274.93362533181;13280.203187808762;6981.89527157093;8220.83558328842;8290.419670622998;8307.916920830836;8378.12029736881;8360.916390836135;8365.1216622255;8384.161583841567;8449.36629752771;8450.19723126302;3983.402489626579;4170.854020109282;4312.439382880269;4393.0774537347515;4426.981794187291;1737.3311275159156;1784.12577836859;1848.196034724899;1848.4177484092468;1874.437668699412;341.6429830826792;400.951885773709;827.6482494939627;922.3359181389388;995.1342332171057;9919.603215871428;11307.604233851776;11314.208005439541;11552.133589980785;11874.050075994073;376.6403085053767;451.7154192858478;457.93588897554366;465.4647155770823;476.4568392033085;6055.6063855849225;6070.512724382555;6121.602095863755;6146.108814221912;6165.815025549241;703336;714679.8532014717;718878;758594;774146;109549;110621.89378106115;110776.33835492474;112137;114023.85976140299;475.6147520508399;479.81527112062105;484.4670862051715;958.9232861371073;1178.1693905796392;14726.63183420423;16612.58468538296;17603.94376337424;17761.934283943006;17803.732944006108;47601.285980709625;49819;50215.24677129773;51579.452616421564;52091.73954130205;66256.66871665804;68553.2861678462;69719;70886;71121.6443917777;7330.926690733238;7552;7660.3488703460835;7670.386369090571;7691.423143264389;9917.603295868084;11801.82297265559;12027.27836329823;12183.390830458264;12230.87769122333;8652.307815374881;10214.438205898747;10329.9483502582;10560.207984401355;10621.468926553796;7177.569345839266;10092.545835437277;10264.743381415226;10437.21720870953;10447.634332798316;7731.729389471341;10126.898606064555;10411.375317480975;10689.572417103229;10754.032137107697;7551.169371369255;7606.847863042597;7614.695412183451;7622.771316860503;7674.8081297968;9432.575534100844;9678.85481717789;10493.2130090242;10692.732681683021;10697.358158510515 05:28:35 INFO - PROCESS | 1676 | |3;mootools.html;1332.5669157523807;1511.115997141672;1540.4531391356072;1767.8762486625933;1942.3104797796782;7726.845463090742;7897.131315555292;8348.624311905964;8540.615672294745;8546.05993340732;8327.417080804345;8387.790305242357;8514.403991720583;8520.275776558985;8567.314614830813;1902.6004539046749;2163.9567208655817;2448.3022338633536;2477.9008839646394;2480.875956202183;2116.5237821490196;2232.9218477353297;2442.9144979925704;2492.003198720508;2492.2274095030507;2410.337157281744;2457.8893949772214;2460.778529932306;2464.445499762548;2473.146764366291;2419.250032489934;2463.310273123528;2464.162184857148;2471.8393304435244;2492.6510288559616;2429;2460.790832779216;2461.433870209844;2468.962965555518;2471.0609968212143;8490.448120872123;8571.314294856396;8579.356548258906;8588.312934965188;8594.312455003646;5313.468653134694;5417.349918009864;5459.235707001023;5466.945330546683;5467.097928841776;3610.1155216971747;3631.7639353442205;3678.797666128362;3758.9248215035545;3790.56408513019;1052.5316234275751;1057.603398725478;1078.2344535379889;1083.0793825248554;1105.8949399807007;11834.467448964731;11841.94079029608;11843.99326057286;11846.229995050295;11848;4834.670465621982;4838.129136755418;4911.18965370717;4923.556879880828;4929.06347793918;9916.900830991743;9923.60305587783;9933.453660048623;9935.354201976848;9963.153131983718;5424.864378390572;5432.891342173134;5437.700926449004;5438.401775804658;5472.343318801769;4888.486708895586;4942.061008408391;4946.356973593482;4953.455119936804;4983.177775667047;3590.784552926833;3665.9450108248398;3666.2850744105176;3891.1828516011956;3930.037140900498;1122.7249323915694;1280.9103362764517;1289.774289499334;1295.6372215779597;1300.14840279617;1301.3363184775833;1308.8298521192376;1321.0752473268558;1342.9059965802496;1349.6153596225224;7868.488548244346;7914.485558438683;8206;8210.343172546181;8221.54781487024;1084.6800193942806;1123.3652986063012;1185.4546908422221;1201.7476329970805;1207.5773479282357;6174.289956654952;6220.688965551704;6274.090256912722;6283.8114856554375;6292.2134733158355;9919.851202231828;10439.739006524745;10561.313514621525;10563.419011954262;10564.841527376942;10225.977402259714;10406.791864162675;10415.635452759117;10444.582216711246;10488.63647725806;8104.351651867836;8291.295239904555;8303.128171542023;8308.54303013328;8310.792230194173;7575.545467271982;7741.264579864904;7754.263344982218;7779.805504862424;7841.882371764314;8120.715774947848;9342.439453632533;9437.867455905336;9500.524973751422;9642.517874106406;9565.04349565038;9762;9793.902060979584;9797.951010244904;9933.15568176713;7300.56196628204;7366.631668416665;7424.70301187968;7434.962825185839;7456.925430745623;7108.218096009331;7287.979682844512;7298.416126709957;7938.642761075881;8042.517448953082;5556.249906262765;7314.817129571803;7383.07711536058;7474.738384156529;7548.584827834302;7220.566765994057;7241.239669834592;7314.707411703473;7451.143118541437;7466.887996680162;7419.22098179705;7421.777346679608;7502.062242219723;7503.212162723054;7504.887426688713;6798.558093723892;6926.896096558657;6946.583205007716;6950.652467376712;6966.233714291323;6987.860242795217;7030.753923612649;7034.613096279753;7042.507024508235;7053.0830991970715 05:28:35 INFO - PROCESS | 1676 | |4;prototype.html;321.29475800617644;325.89408442256274;325.95110733389976;331.78931378574606;335.25405971712956;5315.521603055724;6490.480761539077;6627.801165965023;6651.16860392451;6788.558743681663;6543.7055332510035;6549;6585.111010013647;6700.899486507707;6732.562383445073;4722.693024953375;4807.567318941295;4815.109204797106;4816.638752093607;4855.441624213207;4830.879228019293;4850.223964165734;4873.195922672755;4909.8772530686665;4915.6313276504225;4548.408706868103;4602.470715867685;4638.489766125724;4660.627149828023;4755.667103302771;4650.558196971282;4655.092257009885;4671.8364857229835;4711.316859055435;4714.175019371631;4042.413849991764;4047.0978640039134;4061.208064427438;4066.7763273019973;4067.2272268268935;5713.14302854571;5899.498542623882;6364.395382438661;6394.8721025579225;6441.355864413568;4337.872153240149;5556.805511807067;5580.525655319303;5632.493075623213;5646.491815736563;7052.153741550996;7056.082709247765;7061.682224299919;7069.717211311541;7083.433325333961;4308.9353659695125;4401.691881568291;4567.086582683477;4571.131485017836;4588.220002599566;7287.125544934642;7376.225496322916;7402.851942961111;7407.925920740778;7471.215522370181;4695.460022097434;4843.75781210938;4893.4617192108835;4897.363342765489;4906.950930490721;4872.707637541759;4887.34020907173;4909.656324057281;4931.5315045070665;4940.6788558743565;5589.496945274945;5782.653040817564;5826.184334193216;5832.358440571534;5844.707764611752;7117.395021423134;7117.501774875813;7119.288071192839;7145.1425828900865;7158.60627665473;1690.3323187341177;1718.1151706870971;1719.3896166860584;1721.3028723366806;1723.5001849463515;1096.627146770102;1115.8605174353206;1119.6249256499163;1123.4045955643521;1147.9024282936043;6529.902051469232;6532.346765323429;6533.954567269309;6535.150430444012;6547.116139321226;7324.96337518309;7327.487075904635;7335.156457007406;7354.227806080285;7363.337299643058;6244.750209991549;6489.935100649028;6511.381418765304;6511.804645860631;6554.016897465418;3884.9611503885167;3911.9804400977814;3914.080191155076;3917;3922.431247469101;7682.807929801688;7687.192844751333;7687.923690683308;7690.307872291521;7706.075270967408;7235.059442272471;7543.283388078124;7573.2048134946135;7576.7348142814735;7603.163651998276;7652.540847549165;7655.119661238918;7676.232376762279;7676.654550545127;7715.305622494003;7338.70645174187;7338.853222935405;7345.118585769742;7349.228331025165;7351.191368949519;5327.440618735132;6642.800715978528;6921.515493915377;7188.885722712778;7199.568025918462;6489.4159525641035;7291.234420385783;7604.163542010485;7606.8098297543;7607.391408687403;4857.8299759508245;4858.854234372974;4868.513148685103;4870.245112007645;4888.291197776373;7059.752908648173;7364.926350736424;7389.039424874732;7392.593407362647;7435.330820226206;7126.928730712627;7129.857402852018;7134.678939447634;7136.928630713626;7147.785566433015;6820.181578210647;7113.75101871432;7115.5730656160795;7121.608311542915;7129.928700712927;6973.232944376013;6982.615956122462;6985.987031880247;6989.231184569795;7000.509964302653;6448.774292899726;7095.680694368869;7105.82235444118;7113.57318560888;7115.430765538848;6769.661516924232;6888.655567221719;6962.7911162665205;6985.196702379291;6993.335633114846 05:28:35 INFO - PROCESS | 1676 | |5;yui.html;626.7336382037635;695.7460526907674;717.619661579363;742.6769355330432;780.8516381887441;14692.485762998298;14921.701565968688;14929.477468288613;14936.402543898259;14948.925255373722;15348.6930261395;15744.055356678578;15759.369625215006;15780.526584202493;15788.921055394736;575.6172145523243;695.805174551124;864.2740098317396;877.8376000439907;880.4849163239512;713.7680253917472;847.9448835825682;848.2832006954145;850.230541360067;858.1804376820122;891.073283784863;891.6879092317702;895.1138373010705;895.2032690905099;899.4468401932797;722.6603496356704;731.0277331149558;733.457241641187;890.9821803563925;910.0580898769774;655.3806652713188;655.9770408035696;658.4962503684685;659.3801826283278;666.1140682891743;14947.925260373737;14991.950563460563;15049.849501504954;15068.472603458826;15075.472358467401;12198.451069701796;12243.449044792917;12402.945749611294;12404.25574465535;12620.24278543281;9964.03038760616;9974.451405172713;10007.449590272532;10220.080192782685;10325.948370258177;3464.6535346465403;3466.445368740989;3466.861325546975;3467.081223475781;3617.1680513481783;80550.79173812398;80929.9767505817;81798;82227;82433;8043.507386033739;9264.90735092654;9289.814203716023;9483.66807161746;9613.423194608346;20493.07781149852;20498.38504844857;20505.077271522816;20508.38474845757;20542.897285513776;7486.401087912954;7609.353204977529;7658.693652253847;7705.22947705225;7741;4129.91740165201;4187.330027195695;4191.91616167675;4197.580241975839;4198.748075115503;3518.665726755954;3554.9466757998653;3559.982200089035;3561.821908904544;3575.4458059000895;561.9662820230799;635.3741564558906;656.9605823650596;659.4856012310405;682.3074579302013;488.25297295138495;538.4749868877813;586.4604563801313;590.2032256453753;618.4619381138439;8446.493210407396;8670.739877803677;8683.131686831266;8787.165219304155;8892.828861851362;509.900569388973;549.3309050597871;560.885018571192;604.652324913168;654.3293124547376;3769.396896496602;3930.508686414198;3962.9603703963166;3999.3800960851113;4177.749335039907;83113.58443207745;83837;83919.32161356861;86175.56912215399;87503.56248218718;72642.27357726461;72734.2726572738;73477;73527.63236183891;73911.19577227463;65581;66368;66428.66785666041;66693.99959000522;68018;7459.962700186465;8815.735527934172;8885.37802353855;8970.775730606787;9030.729078127666;44778.104437910406;46873;47157.52842471531;47484.7625761869;47911.5208847921;50887.473375798785;61182.38817611767;61820;64877.67561162353;65001.674991626634;10589.152867770406;10633.787324253317;10829.187810914056;11045.723856903642;11190.552377904793;10317.896821031994;11714.472848721998;12282.508699652271;12362;12684.809727854274;10547.103496202908;10754.193435492212;11099.112071034137;11218.607348742755;11365.659030229106;11104.752141175588;11160.832587511357;11265.549378024787;11612.477438515456;12000.819987700366;10637.414942177946;10937.015668589866;11097.389643569359;11290.661280161608;12118.636440906786;8167.509949403055;8695.391322607356;8874.378793484395;8940.910590894007;9127.680531181377;8962.283017358726;9758.316917815684;9771.75570610712;9777.364471309342;9889.653862114792 05:28:35 INFO - PROCESS | 1676 | __end_tp_report 05:28:35 INFO - PROCESS | 1676 | __start_cc_report 05:28:35 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1128 05:28:35 INFO - PROCESS | 1676 | __end_cc_report 05:28:35 INFO - PROCESS | 1676 | __startTimestamp1454678915562__endTimestamp 05:28:35 INFO - PROCESS | 1676 | 05:28:35 INFO - PROCESS | 1676 | ------- Summary: start ------- 05:28:35 INFO - PROCESS | 1676 | Number of tests: 6 05:28:35 INFO - PROCESS | 1676 | 05:28:35 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5725.41 Median:5926.17 stddev:629.41 (10.6%) stddev-sans-first:605.00 05:28:35 INFO - PROCESS | 1676 | Values: 3428.4 3459.4 3494.5 3498.9 3536.0 5612.2 5688.9 5690.4 5717.7 5740.4 5654.8 5718.2 5729.8 5740.1 5746.1 5042.3 5110.0 5134.2 5172.3 5222.9 5225.9 5226.5 5235.0 5235.2 5236.0 5196.4 5202.1 5204.3 5207.6 5208.0 5195.2 5203.4 5209.5 5219.0 5221.6 4589.3 5139.7 5243.2 5257.6 5261.3 5646.9 5694.5 5694.8 5752.1 5759.0 6200.2 6221.3 6240.3 6264.1 6275.1 6116.6 6136.5 6185.6 6201.3 6316.7 5769.7 5799.5 5813.2 5833.3 5862.4 6231.9 6273.2 6285.7 6301.2 6314.0 6004.9 6113.1 6119.8 6123.2 6125.8 6095.8 6157.9 6158.5 6209.7 6217.9 6133.2 6177.1 6250.7 6253.8 6255.5 6226.5 6227.9 6229.5 6240.1 6240.4 4805.0 4867.8 4871.6 4880.0 4911.8 4374.8 4376.3 4391.8 4393.6 4435.3 5596.2 5644.4 5648.5 5650.7 5655.2 6219.3 6220.1 6247.1 6263.8 6320.0 5665.3 5675.1 5688.6 5691.4 5742.3 5363.2 5363.5 5368.5 5368.5 5371.5 6219.7 6238.6 6251.3 6266.0 6276.7 6288.9 6296.2 6297.5 6309.3 6322.7 6288.8 6292.0 6300.7 6308.5 6308.6 6032.8 6095.3 6110.7 6114.1 6206.7 5258.0 5921.6 6312.0 6313.5 6320.2 6085.7 6106.9 6110.3 6111.6 6112.5 5945.3 6002.3 6010.8 6050.4 6115.5 5872.4 5926.2 5933.0 5948.5 5955.8 6015.0 6091.4 6103.9 6113.3 6120.3 5751.2 5781.0 5850.5 5925.0 6027.5 05:28:35 INFO - PROCESS | 1676 | 05:28:35 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:57951.67 Median:37361.53 stddev:116301.06 (311.3%) stddev-sans-first:116547.20 05:28:35 INFO - PROCESS | 1676 | Values: 459.3 467.1 480.9 580.6 616.3 28217.6 30365.0 38168.0 38994.8 39208.0 41821.8 42212.6 42388.4 42528.4 42539.9 9505.1 9748.1 9754.0 9754.5 9767.1 9458.8 9598.8 9610.7 9612.1 9625.7 9693.1 9769.6 9774.8 9779.0 9789.4 9688.3 9769.6 9774.1 9778.3 9789.0 9566.5 9688.4 9691.3 9714.5 9741.0 40115.4 40429.2 40443.4 40531.2 40545.6 3272.4 3454.1 3494.7 3639.7 3790.6 28630.9 28880.9 29261.6 29279.7 29391.4 2773.6 2822.8 2868.2 2869.6 2879.7 373331.0 373812.1 374016.0 374367.0 375342.0 56395.0 57422.4 57480.4 63437.7 65542.7 60932.1 61324.0 61579.4 61606.4 61697.8 16673.6 16879.9 16886.7 16897.2 16921.2 11496.5 11706.8 11739.0 12691.0 12755.5 18536.8 18636.1 20481.3 20485.0 20577.7 7773.6 7913.9 7927.7 7939.0 7952.4 959.0 973.6 985.8 985.9 1005.3 26193.7 29381.7 29602.4 29818.6 30463.7 1042.5 1066.0 1101.5 1129.6 1134.3 14317.6 14422.8 14474.7 14676.0 14824.1 545003.5 551702.0 553787.0 554136.0 556809.0 47091.0 49059.0 56065.9 57052.7 57351.0 1400.3 1402.7 1441.0 1441.1 1443.0 23986.8 26312.6 27943.4 28085.0 28767.7 50961.7 51378.0 51382.7 51516.5 51531.9 358432.0 360016.0 360634.0 360797.0 363119.0 29598.4 29865.3 30182.1 30428.4 30434.1 41862.4 43709.3 44523.3 44536.3 45686.5 35406.9 36536.6 37299.3 37376.4 37426.3 32650.2 33432.5 36646.8 37216.9 37346.6 36340.1 36952.6 36960.6 37230.1 37241.0 30637.4 32335.7 32461.5 32493.3 33152.0 24060.2 24503.1 24556.9 24786.9 24793.8 05:28:35 INFO - PROCESS | 1676 | 05:28:35 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:36011.88 Median:454.83 stddev:120909.92 (26583.8%) stddev-sans-first:121219.47 05:28:35 INFO - PROCESS | 1676 | Values: 362.2 366.2 368.2 370.7 374.0 12396.4 15471.3 18805.5 19438.2 20060.5 10048.7 11685.5 12031.8 18796.2 19039.8 5057.3 5228.9 5232.9 5301.0 5301.3 3802.3 3997.0 4005.8 5529.1 5777.5 4750.8 5658.7 5735.5 5757.5 5769.4 5729.7 5731.1 5739.1 5739.2 5741.4 5340.3 5404.0 5446.5 5456.4 5589.2 12955.5 13058.6 21932.6 23506.5 24290.3 1822.8 2108.4 2262.7 2281.3 2779.3 9771.7 9877.6 9889.3 9891.6 9911.6 1731.4 1829.5 1836.8 1849.7 1861.5 87735.0 90255.0 90662.1 90915.5 92532.1 10773.2 10976.6 10986.7 11000.4 11028.9 13190.0 13246.5 13270.1 13274.9 13280.2 6981.9 8220.8 8290.4 8307.9 8378.1 8360.9 8365.1 8384.2 8449.4 8450.2 3983.4 4170.9 4312.4 4393.1 4427.0 1737.3 1784.1 1848.2 1848.4 1874.4 341.6 401.0 827.6 922.3 995.1 9919.6 11307.6 11314.2 11552.1 11874.1 376.6 451.7 457.9 465.5 476.5 6055.6 6070.5 6121.6 6146.1 6165.8 703336.0 714679.9 718878.0 758594.0 774146.0 109549.0 110621.9 110776.3 112137.0 114023.9 475.6 479.8 484.5 958.9 1178.2 14726.6 16612.6 17603.9 17761.9 17803.7 47601.3 49819.0 50215.2 51579.5 52091.7 66256.7 68553.3 69719.0 70886.0 71121.6 7330.9 7552.0 7660.3 7670.4 7691.4 9917.6 11801.8 12027.3 12183.4 12230.9 8652.3 10214.4 10329.9 10560.2 10621.5 7177.6 10092.5 10264.7 10437.2 10447.6 7731.7 10126.9 10411.4 10689.6 10754.0 7551.2 7606.8 7614.7 7622.8 7674.8 9432.6 9678.9 10493.2 10692.7 10697.4 05:28:35 INFO - PROCESS | 1676 | 05:28:35 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5957.85 Median:5463.09 stddev:3103.91 (56.8%) stddev-sans-first:3093.14 05:28:35 INFO - PROCESS | 1676 | Values: 1332.6 1511.1 1540.5 1767.9 1942.3 7726.8 7897.1 8348.6 8540.6 8546.1 8327.4 8387.8 8514.4 8520.3 8567.3 1902.6 2164.0 2448.3 2477.9 2480.9 2116.5 2232.9 2442.9 2492.0 2492.2 2410.3 2457.9 2460.8 2464.4 2473.1 2419.3 2463.3 2464.2 2471.8 2492.7 2429.0 2460.8 2461.4 2469.0 2471.1 8490.4 8571.3 8579.4 8588.3 8594.3 5313.5 5417.3 5459.2 5466.9 5467.1 3610.1 3631.8 3678.8 3758.9 3790.6 1052.5 1057.6 1078.2 1083.1 1105.9 11834.5 11841.9 11844.0 11846.2 11848.0 4834.7 4838.1 4911.2 4923.6 4929.1 9916.9 9923.6 9933.5 9935.4 9963.2 5424.9 5432.9 5437.7 5438.4 5472.3 4888.5 4942.1 4946.4 4953.5 4983.2 3590.8 3665.9 3666.3 3891.2 3930.0 1122.7 1280.9 1289.8 1295.6 1300.1 1301.3 1308.8 1321.1 1342.9 1349.6 7868.5 7914.5 8206.0 8210.3 8221.5 1084.7 1123.4 1185.5 1201.7 1207.6 6174.3 6220.7 6274.1 6283.8 6292.2 9919.9 10439.7 10561.3 10563.4 10564.8 10226.0 10406.8 10415.6 10444.6 10488.6 8104.4 8291.3 8303.1 8308.5 8310.8 7575.5 7741.3 7754.3 7779.8 7841.9 8120.7 9342.4 9437.9 9500.5 9642.5 9565.0 9762.0 9793.9 9798.0 9933.2 7300.6 7366.6 7424.7 7435.0 7456.9 7108.2 7288.0 7298.4 7938.6 8042.5 5556.2 7314.8 7383.1 7474.7 7548.6 7220.6 7241.2 7314.7 7451.1 7466.9 7419.2 7421.8 7502.1 7503.2 7504.9 6798.6 6926.9 6946.6 6950.7 6966.2 6987.9 7030.8 7034.6 7042.5 7053.1 05:28:35 INFO - PROCESS | 1676 | 05:28:35 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:5779.22 Median:6533.15 stddev:1839.43 (28.2%) stddev-sans-first:1798.40 05:28:35 INFO - PROCESS | 1676 | Values: 321.3 325.9 326.0 331.8 335.3 5315.5 6490.5 6627.8 6651.2 6788.6 6543.7 6549.0 6585.1 6700.9 6732.6 4722.7 4807.6 4815.1 4816.6 4855.4 4830.9 4850.2 4873.2 4909.9 4915.6 4548.4 4602.5 4638.5 4660.6 4755.7 4650.6 4655.1 4671.8 4711.3 4714.2 4042.4 4047.1 4061.2 4066.8 4067.2 5713.1 5899.5 6364.4 6394.9 6441.4 4337.9 5556.8 5580.5 5632.5 5646.5 7052.2 7056.1 7061.7 7069.7 7083.4 4308.9 4401.7 4567.1 4571.1 4588.2 7287.1 7376.2 7402.9 7407.9 7471.2 4695.5 4843.8 4893.5 4897.4 4907.0 4872.7 4887.3 4909.7 4931.5 4940.7 5589.5 5782.7 5826.2 5832.4 5844.7 7117.4 7117.5 7119.3 7145.1 7158.6 1690.3 1718.1 1719.4 1721.3 1723.5 1096.6 1115.9 1119.6 1123.4 1147.9 6529.9 6532.3 6534.0 6535.2 6547.1 7325.0 7327.5 7335.2 7354.2 7363.3 6244.8 6489.9 6511.4 6511.8 6554.0 3885.0 3912.0 3914.1 3917.0 3922.4 7682.8 7687.2 7687.9 7690.3 7706.1 7235.1 7543.3 7573.2 7576.7 7603.2 7652.5 7655.1 7676.2 7676.7 7715.3 7338.7 7338.9 7345.1 7349.2 7351.2 5327.4 6642.8 6921.5 7188.9 7199.6 6489.4 7291.2 7604.2 7606.8 7607.4 4857.8 4858.9 4868.5 4870.2 4888.3 7059.8 7364.9 7389.0 7392.6 7435.3 7126.9 7129.9 7134.7 7136.9 7147.8 6820.2 7113.8 7115.6 7121.6 7129.9 6973.2 6982.6 6986.0 6989.2 7000.5 6448.8 7095.7 7105.8 7113.6 7115.4 6769.7 6888.7 6962.8 6985.2 6993.3 05:28:35 INFO - PROCESS | 1676 | 05:28:35 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:17585.16 Median:31219.23 stddev:24212.49 (77.6%) stddev-sans-first:24246.92 05:28:35 INFO - PROCESS | 1676 | Values: 626.7 695.7 717.6 742.7 780.9 14692.5 14921.7 14929.5 14936.4 14948.9 15348.7 15744.1 15759.4 15780.5 15788.9 575.6 695.8 864.3 877.8 880.5 713.8 847.9 848.3 850.2 858.2 891.1 891.7 895.1 895.2 899.4 722.7 731.0 733.5 891.0 910.1 655.4 656.0 658.5 659.4 666.1 14947.9 14992.0 15049.8 15068.5 15075.5 12198.5 12243.4 12402.9 12404.3 12620.2 9964.0 9974.5 10007.4 10220.1 10325.9 3464.7 3466.4 3466.9 3467.1 3617.2 80550.8 80930.0 81798.0 82227.0 82433.0 8043.5 9264.9 9289.8 9483.7 9613.4 20493.1 20498.4 20505.1 20508.4 20542.9 7486.4 7609.4 7658.7 7705.2 7741.0 4129.9 4187.3 4191.9 4197.6 4198.7 3518.7 3554.9 3560.0 3561.8 3575.4 562.0 635.4 657.0 659.5 682.3 488.3 538.5 586.5 590.2 618.5 8446.5 8670.7 8683.1 8787.2 8892.8 509.9 549.3 560.9 604.7 654.3 3769.4 3930.5 3963.0 3999.4 4177.7 83113.6 83837.0 83919.3 86175.6 87503.6 72642.3 72734.3 73477.0 73527.6 73911.2 65581.0 66368.0 66428.7 66694.0 68018.0 7460.0 8815.7 8885.4 8970.8 9030.7 44778.1 46873.0 47157.5 47484.8 47911.5 50887.5 61182.4 61820.0 64877.7 65001.7 10589.2 10633.8 10829.2 11045.7 11190.6 10317.9 11714.5 12282.5 12362.0 12684.8 10547.1 10754.2 11099.1 11218.6 11365.7 11104.8 11160.8 11265.5 11612.5 12000.8 10637.4 10937.0 11097.4 11290.7 12118.6 8167.5 8695.4 8874.4 8940.9 9127.7 8962.3 9758.3 9771.8 9777.4 9889.7 05:28:35 INFO - PROCESS | 1676 | -------- Summary: end -------- 05:28:35 INFO - PROCESS | 1676 | 05:28:36 INFO - TEST-INFO | 1676: exit 0 05:28:36 INFO - TEST-OK | dromaeo_css | took 1115189ms 05:28:36 INFO - TEST-START | kraken 05:28:36 INFO - operating with platform_type : mac_ 05:28:36 INFO - Initialising browser for kraken test... 05:28:36 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpZ1jBkj/profile http://localhost:49284/getInfo.html) 05:28:36 INFO - PROCESS | 1682 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 05:28:38 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 05:28:38 INFO - PROCESS | 1682 | colorDepth:24 05:28:38 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 05:28:38 INFO - PROCESS | 1682 | __metrics 05:28:38 INFO - PROCESS | 1682 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:28:38 INFO - PROCESS | 1682 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:28:38 INFO - TEST-INFO | 1682: exit 0 05:28:38 INFO - Browser initialized. 05:28:38 INFO - Running cycle 1/1 for kraken test... 05:28:38 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpZ1jBkj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:50 INFO - PROCESS | 1685 | RSS: Main: 245760000 05:28:50 INFO - PROCESS | 1685 | 05:28:53 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:53 INFO - PROCESS | 1685 | RSS: Main: 290287616 05:28:53 INFO - PROCESS | 1685 | 05:28:56 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-dft) 05:28:57 INFO - PROCESS | 1685 | RSS: Main: 302235648 05:28:57 INFO - PROCESS | 1685 | 05:29:00 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-fft) 05:29:01 INFO - PROCESS | 1685 | RSS: Main: 287449088 05:29:01 INFO - PROCESS | 1685 | 05:29:03 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator) 05:29:03 INFO - PROCESS | 1685 | RSS: Main: 290205696 05:29:03 INFO - PROCESS | 1685 | 05:29:05 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:06 INFO - PROCESS | 1685 | RSS: Main: 292003840 05:29:06 INFO - PROCESS | 1685 | 05:29:09 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:09 INFO - PROCESS | 1685 | RSS: Main: 292290560 05:29:09 INFO - PROCESS | 1685 | 05:29:12 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:13 INFO - PROCESS | 1685 | RSS: Main: 291721216 05:29:13 INFO - PROCESS | 1685 | 05:29:15 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial) 05:29:16 INFO - PROCESS | 1685 | RSS: Main: 293384192 05:29:16 INFO - PROCESS | 1685 | 05:29:18 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:18 INFO - PROCESS | 1685 | RSS: Main: 327360512 05:29:18 INFO - PROCESS | 1685 | 05:29:20 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:21 INFO - PROCESS | 1685 | RSS: Main: 326885376 05:29:21 INFO - PROCESS | 1685 | 05:29:23 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:23 INFO - PROCESS | 1685 | RSS: Main: 333078528 05:29:23 INFO - PROCESS | 1685 | 05:29:26 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:26 INFO - PROCESS | 1685 | RSS: Main: 334823424 05:29:26 INFO - PROCESS | 1685 | 05:29:29 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:29 INFO - PROCESS | 1685 | RSS: Main: 335425536 05:29:29 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49284/tests/kraken/driver.html?testName=ai-astar) 05:29:31 INFO - PROCESS | 1685 | RSS: Main: 395984896 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | __start_tp_report 05:29:31 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 05:29:31 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 05:29:31 INFO - PROCESS | 1685 | |i|pagename|runs| 05:29:31 INFO - PROCESS | 1685 | |0;ai-astar;109;111;111;111;110;108;114;111;111;110 05:29:31 INFO - PROCESS | 1685 | |1;audio-beat-detection;155;119;174;185;143;141;138;118;157;119 05:29:31 INFO - PROCESS | 1685 | |2;audio-dft;184;185;184;185;184;185;183;184;184;198 05:29:31 INFO - PROCESS | 1685 | |3;audio-fft;81;75;87;76;56;49;56;76;100;80 05:29:31 INFO - PROCESS | 1685 | |4;audio-oscillator;90;101;140;69;102;114;143;111;91;103 05:29:31 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;86;92;86;93;86;92;86;91;86;92 05:29:31 INFO - PROCESS | 1685 | |6;imaging-darkroom;95;93;95;94;95;95;94;95;95;95 05:29:31 INFO - PROCESS | 1685 | |7;imaging-desaturate;87;87;88;87;84;114;139;112;87;87 05:29:31 INFO - PROCESS | 1685 | |8;json-parse-financial;55;56;53;52;54;54;54;63;52;54 05:29:31 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;65;62;70;78;75;72;69;67;66;68 05:29:31 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;82;82;83;82;83;82;81;81;81;83 05:29:31 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;124;122;125;121;110;126;125;131;134;119 05:29:31 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;127;128;136;137;137;120;172;173;137;137 05:29:31 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;67;66;66;67;66;66;67;67;66;69 05:29:31 INFO - PROCESS | 1685 | __end_tp_report 05:29:31 INFO - PROCESS | 1685 | __start_cc_report 05:29:31 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,1159 05:29:31 INFO - PROCESS | 1685 | __end_cc_report 05:29:31 INFO - PROCESS | 1685 | __startTimestamp1454678971884__endTimestamp 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | ------- Summary: start ------- 05:29:31 INFO - PROCESS | 1685 | Number of tests: 14 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:1.58 (1.4%) stddev-sans-first:1.56 05:29:31 INFO - PROCESS | 1685 | Values: 109.0 111.0 111.0 111.0 110.0 108.0 114.0 111.0 111.0 110.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:149.00 stddev:23.18 (15.6%) stddev-sans-first:24.29 05:29:31 INFO - PROCESS | 1685 | Values: 155.0 119.0 174.0 185.0 143.0 141.0 138.0 118.0 157.0 119.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:185.60 Median:184.50 stddev:4.40 (2.4%) stddev-sans-first:4.63 05:29:31 INFO - PROCESS | 1685 | Values: 184.0 185.0 184.0 185.0 184.0 185.0 183.0 184.0 184.0 198.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:73.60 Median:76.00 stddev:15.67 (20.6%) stddev-sans-first:16.39 05:29:31 INFO - PROCESS | 1685 | Values: 81.0 75.0 87.0 76.0 56.0 49.0 56.0 76.0 100.0 80.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:106.40 Median:141.50 stddev:22.39 (15.8%) stddev-sans-first:22.95 05:29:31 INFO - PROCESS | 1685 | Values: 90.0 101.0 140.0 69.0 102.0 114.0 143.0 111.0 91.0 103.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:91.50 stddev:3.20 (3.5%) stddev-sans-first:3.20 05:29:31 INFO - PROCESS | 1685 | Values: 86.0 92.0 86.0 93.0 86.0 92.0 86.0 91.0 86.0 92.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 05:29:31 INFO - PROCESS | 1685 | Values: 95.0 93.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:87.00 stddev:18.34 (21.1%) stddev-sans-first:19.08 05:29:31 INFO - PROCESS | 1685 | Values: 87.0 87.0 88.0 87.0 84.0 114.0 139.0 112.0 87.0 87.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:54.00 stddev:3.16 (5.9%) stddev-sans-first:3.35 05:29:31 INFO - PROCESS | 1685 | Values: 55.0 56.0 53.0 52.0 54.0 54.0 54.0 63.0 52.0 54.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.50 stddev:4.78 (6.9%) stddev-sans-first:4.82 05:29:31 INFO - PROCESS | 1685 | Values: 65.0 62.0 70.0 78.0 75.0 72.0 69.0 67.0 66.0 68.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 05:29:31 INFO - PROCESS | 1685 | Values: 82.0 82.0 83.0 82.0 83.0 82.0 81.0 81.0 81.0 83.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:125.00 stddev:6.57 (5.3%) stddev-sans-first:6.96 05:29:31 INFO - PROCESS | 1685 | Values: 124.0 122.0 125.0 121.0 110.0 126.0 125.0 131.0 134.0 119.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.40 Median:137.00 stddev:17.88 (13.0%) stddev-sans-first:18.29 05:29:31 INFO - PROCESS | 1685 | Values: 127.0 128.0 136.0 137.0 137.0 120.0 172.0 173.0 137.0 137.0 05:29:31 INFO - PROCESS | 1685 | 05:29:31 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 05:29:31 INFO - PROCESS | 1685 | Values: 67.0 66.0 66.0 67.0 66.0 66.0 67.0 67.0 66.0 69.0 05:29:31 INFO - PROCESS | 1685 | -------- Summary: end -------- 05:29:31 INFO - PROCESS | 1685 | 05:29:32 INFO - TEST-INFO | 1685: exit 0 05:29:32 INFO - TEST-OK | kraken | took 56661ms 05:29:32 INFO - SUITE-END | took 1171s 05:29:33 INFO - Completed test suite (00:19:32) 05:29:33 INFO - Working with test: dromaeo_css 05:29:33 INFO - Generating results file: dromaeo_css 05:29:33 INFO - Working with test: kraken 05:29:33 INFO - Generating results file: kraken 05:29:33 INFO - javascript benchmark 05:29:33 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:29:33 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,59]] 05:29:33 INFO - process_Request line: dromaeo_css 7315.60 graph.html#tests=[[72,1,59]] 05:29:33 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:29:33 INFO - process_Request line: kraken 1438.60 graph.html#tests=[[232,1,59]] 05:29:33 INFO - RETURN: dromaeo_css: 7315.60 05:29:33 INFO - RETURN: kraken: 1438.60 05:29:33 INFO - javascript benchmark 05:29:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3428.4171690812564, 3459.4118999770035, 3494.5107684924105, 3498.9300213995707, 3535.9823200883993, 5612.242347283115, 5688.943110568893, 5690.402507736685, 5717.714114294285, 5740.368559458456, 5654.830355089354, 5718.228039214715, 5729.828105156842, 5740.138979153129, 5746.109353050273, 5042.268871013701, 5109.974450127745, 5134.204198349262, 5172.327597412331, 5222.9477705223035, 5225.921611175816, 5226.503482169188, 5234.973825130888, 5235.240890070937, 5236.0, 5196.350456192976, 5202.089634313998, 5204.297419848329, 5207.609429292799, 5208.010478009185, 5195.194744814522, 5203.427622961472, 5209.453007434203, 5218.973905130469, 5221.582273418117, 4589.33454649074, 5139.717315547643, 5243.213517972296, 5257.553107985826, 5261.316028916254, 5646.943530564683, 5694.4590263924865, 5694.77220911163, 5752.0796672532615, 5759.0, 6200.162977997958, 6221.253449586078, 6240.282367527701, 6264.060390941349, 6275.121482992385, 6116.609690437446, 6136.539759518054, 6185.56701030928, 6201.255849298068, 6316.652584107872, 5769.740361683735, 5799.507041901401, 5813.157092221604, 5833.270841144857, 5862.38444963277, 6231.906521402183, 6273.215848018997, 6285.685715714196, 6301.180846489927, 6314.02132669437, 6004.909926351108, 6113.052476866004, 6119.816405507842, 6123.203983482208, 6125.84685382869, 6095.786647467317, 6157.922363586355, 6158.507319414436, 6209.658468784261, 6217.875642487215, 6133.202683651097, 6177.135201071854, 6250.74997000124, 6253.78111766086, 6255.530835187288, 6226.470749986212, 6227.906581401283, 6229.53278504114, 6240.126382306481, 6240.4071613196675, 4804.99095190014, 4867.805287788448, 4871.585915197177, 4880.0, 4911.8280860169725, 4374.803133858984, 4376.277914144131, 4391.802368893407, 4393.582609652078, 4435.3125265583885, 5596.216529685766, 5644.435556444323, 5648.519875810603, 5650.717464126859, 5655.1799989001365, 6219.346968568326, 6220.098085777628, 6247.125402443661, 6263.812085637438, 6320.0, 5665.263515742927, 5675.091985282335, 5688.63023903445, 5691.402402747734, 5742.3396309424115, 5363.249145119687, 5363.544098751572, 5368.489993450616, 5368.5436737876935, 5371.543418809445, 6219.657918814418, 6238.625682459067, 6251.343608920998, 6266.0, 6276.717547710456, 6288.874222515616, 6296.181496405621, 6297.4647154992335, 6309.2744334400295, 6322.683865806784, 6288.8427789303805, 6292.0, 6300.7479700811455, 6308.463780578702, 6308.589941653778, 6032.75868965254, 6095.256985594852, 6110.680268050674, 6114.145302971391, 6206.689665516616, 5257.973710131425, 5921.555883308855, 6311.96844015777, 6313.494920406265, 6320.209973753281, 6085.66528840918, 6106.877862442638, 6110.26676798787, 6111.572189946839, 6112.541559382975, 5945.34601193877, 6002.309734380602, 6010.849728756816, 6050.42520960508, 6115.541334400027, 5872.354041055395, 5926.170336153028, 5933.0, 5948.464638182584, 5955.821325360246, 6015.0375939851165, 6091.42131497507, 6103.908441373294, 6113.327533971171, 6120.326764056039, 5751.194832723339, 5780.971095144666, 5850.502707269759, 5925.0, 6027.457528822427], "unit": "score", "name": "dojo.html", "value": 5686.042519536891}, {"lowerIsBetter": false, "replicates": [459.3224993135126, 467.13579877227113, 480.9275316045217, 580.564576567574, 616.2851092732429, 28217.576736348972, 30365.0, 38168.0, 38994.83015509532, 39208.0, 41821.79089104558, 42212.57787422125, 42388.364174537404, 42528.362074568904, 42539.93650158748, 9505.097015783489, 9748.073932976356, 9753.95123024384, 9754.46350450725, 9767.120959113678, 9458.81082378352, 9598.76003099921, 9610.711678649617, 9612.134907858292, 9625.663101791439, 9693.127618514332, 9769.609215631403, 9774.755631109243, 9779.0, 9789.363691360075, 9688.273379496532, 9769.609215631366, 9774.071463210983, 9778.315517913748, 9788.999999999964, 9566.47384393858, 9688.370255933341, 9691.321607487478, 9714.465704386255, 9741.025897410273, 40115.39826902599, 40429.19141617151, 40443.39334909979, 40531.189376212606, 40545.59454405477, 3272.427325218101, 3454.101933497309, 3494.72042236623, 3639.7452178347526, 3790.58303586608, 28630.85684571564, 28880.85559572189, 29261.610758386414, 29279.70720292791, 29391.41217175666, 2773.6394268745134, 2822.7741780657702, 2868.225579093633, 2869.58391033299, 2879.6976317486574, 373331.0, 373812.1309393463, 374016.0, 374367.0, 375342.0, 56395.0, 57422.425775742544, 57480.42519574834, 63437.68281158657, 65542.68914621681, 60932.08601870976, 61324.0, 61579.38420615737, 61606.383936160964, 61697.76604467886, 16673.583160421087, 16879.902806317794, 16886.662266754844, 16897.239624216938, 16921.238544265536, 11496.54013839437, 11706.765864682659, 11739.0, 12691.0, 12755.489780408865, 18536.81463185378, 18636.068196590117, 20481.344047625018, 20484.97575121238, 20577.69133462999, 7773.5724535149975, 7913.881291780628, 7927.679414718877, 7939.0, 7952.3638108951145, 958.9760255993516, 973.5764942250144, 985.7979114281555, 985.8669079674296, 1005.2711783956573, 26193.73806261951, 29381.706182938324, 29602.407951840843, 29818.55272170919, 30463.695363046085, 1042.5412818359898, 1065.984010239847, 1101.515333253366, 1129.5877004893155, 1134.2627292260097, 14317.6420589484, 14422.765119956888, 14474.710505790035, 14675.972681912377, 14824.110553366832, 545003.5499644952, 551702.0, 553787.0, 554136.0, 556809.0, 47091.0, 49059.01881962341, 56065.878682426126, 57052.71473642605, 57351.0, 1400.3278426355407, 1402.7404930087966, 1440.9927950360182, 1441.0849110814709, 1442.9711405772036, 23986.760132398453, 26312.605310920735, 27943.441131176856, 28085.017024404864, 28767.712322876505, 50961.7451912738, 51377.972440551726, 51382.74308628433, 51516.48483515117, 51531.90808551499, 358432.0, 360016.0, 360634.0, 360797.0, 363119.0, 29598.408031839674, 29865.25336866509, 30182.09453716392, 30428.39143217079, 30434.086977390714, 41862.372064419666, 43709.34435983526, 44523.33215001713, 44536.331955020054, 45686.54313456823, 35406.93779186629, 36536.634633653324, 37299.25401491901, 37376.439353410264, 37426.25147497089, 32650.204238767095, 33432.49851252282, 36646.816765917065, 37216.88349349524, 37346.626533734314, 36340.09149771277, 36952.630473695994, 36960.63039369572, 37230.06924826901, 37241.0, 30637.387252255277, 32335.676643233266, 32461.513077303385, 32493.28847317173, 33152.0, 24060.15789447361, 24503.142390016263, 24556.877215613807, 24786.92720283542, 24793.752062479143], "unit": "score", "name": "ext.html", "value": 19225.862157015097}, {"lowerIsBetter": false, "replicates": [362.23387535362707, 366.2071614953627, 368.1771241275749, 370.7034372501998, 374.01447186663137, 12396.442160102795, 15471.303791329388, 18805.529861753446, 19438.222471101155, 20060.498487537818, 10048.748781280472, 11685.474153663085, 12031.759364812699, 18796.24815007398, 19039.809601903977, 5057.342545469084, 5228.92156617651, 5232.895342093156, 5301.045811753883, 5301.3373328333955, 3802.315583195024, 3997.000749812547, 4005.83976640934, 5529.115341545354, 5777.451142141511, 4750.76246187691, 5658.7170641467965, 5735.512481439062, 5757.510611598019, 5769.3653698093385, 5729.713514324309, 5731.083026715726, 5739.081746920494, 5739.196512488255, 5741.368449470555, 5340.279062326575, 5404.0, 5446.537044351235, 5456.427075157091, 5589.217509548666, 12955.48178072876, 13058.608241752763, 21932.561348772935, 23506.529869402515, 24290.271291861274, 1822.7903791064066, 2108.388567315477, 2262.6832243485924, 2281.3042022183226, 2779.3051737065734, 9771.657991970318, 9877.60489580416, 9889.307748457612, 9891.604335826558, 9911.571664942136, 1731.3593970231095, 1829.5060333709823, 1836.8255015773614, 1849.6948003579394, 1861.4601765488128, 87735.0, 90255.0, 90662.09337906602, 90915.54542227247, 92532.07467925301, 10773.24587278883, 10976.614994180021, 10986.670399888017, 11000.449977501094, 11028.889711102785, 13190.0, 13246.470141194244, 13270.071095023255, 13274.93362533181, 13280.203187808762, 6981.89527157093, 8220.83558328842, 8290.419670622998, 8307.916920830836, 8378.12029736881, 8360.916390836135, 8365.1216622255, 8384.161583841567, 8449.36629752771, 8450.19723126302, 3983.402489626579, 4170.854020109282, 4312.439382880269, 4393.0774537347515, 4426.981794187291, 1737.3311275159156, 1784.12577836859, 1848.196034724899, 1848.4177484092468, 1874.437668699412, 341.6429830826792, 400.951885773709, 827.6482494939627, 922.3359181389388, 995.1342332171057, 9919.603215871428, 11307.604233851776, 11314.208005439541, 11552.133589980785, 11874.050075994073, 376.6403085053767, 451.7154192858478, 457.93588897554366, 465.4647155770823, 476.4568392033085, 6055.6063855849225, 6070.512724382555, 6121.602095863755, 6146.108814221912, 6165.815025549241, 703336.0, 714679.8532014717, 718878.0, 758594.0, 774146.0, 109549.0, 110621.89378106115, 110776.33835492474, 112137.0, 114023.85976140299, 475.6147520508399, 479.81527112062105, 484.4670862051715, 958.9232861371073, 1178.1693905796392, 14726.63183420423, 16612.58468538296, 17603.94376337424, 17761.934283943006, 17803.732944006108, 47601.285980709625, 49819.0, 50215.24677129773, 51579.452616421564, 52091.73954130205, 66256.66871665804, 68553.2861678462, 69719.0, 70886.0, 71121.6443917777, 7330.926690733238, 7552.0, 7660.3488703460835, 7670.386369090571, 7691.423143264389, 9917.603295868084, 11801.82297265559, 12027.27836329823, 12183.390830458264, 12230.87769122333, 8652.307815374881, 10214.438205898747, 10329.9483502582, 10560.207984401355, 10621.468926553796, 7177.569345839266, 10092.545835437277, 10264.743381415226, 10437.21720870953, 10447.634332798316, 7731.729389471341, 10126.898606064555, 10411.375317480975, 10689.572417103229, 10754.032137107697, 7551.169371369255, 7606.847863042597, 7614.695412183451, 7622.771316860503, 7674.8081297968, 9432.575534100844, 9678.85481717789, 10493.2130090242, 10692.732681683021, 10697.358158510515], "unit": "score", "name": "jquery.html", "value": 8251.681309134954}, {"lowerIsBetter": false, "replicates": [1332.5669157523807, 1511.115997141672, 1540.4531391356072, 1767.8762486625933, 1942.3104797796782, 7726.845463090742, 7897.131315555292, 8348.624311905964, 8540.615672294745, 8546.05993340732, 8327.417080804345, 8387.790305242357, 8514.403991720583, 8520.275776558985, 8567.314614830813, 1902.6004539046749, 2163.9567208655817, 2448.3022338633536, 2477.9008839646394, 2480.875956202183, 2116.5237821490196, 2232.9218477353297, 2442.9144979925704, 2492.003198720508, 2492.2274095030507, 2410.337157281744, 2457.8893949772214, 2460.778529932306, 2464.445499762548, 2473.146764366291, 2419.250032489934, 2463.310273123528, 2464.162184857148, 2471.8393304435244, 2492.6510288559616, 2429.0, 2460.790832779216, 2461.433870209844, 2468.962965555518, 2471.0609968212143, 8490.448120872123, 8571.314294856396, 8579.356548258906, 8588.312934965188, 8594.312455003646, 5313.468653134694, 5417.349918009864, 5459.235707001023, 5466.945330546683, 5467.097928841776, 3610.1155216971747, 3631.7639353442205, 3678.797666128362, 3758.9248215035545, 3790.56408513019, 1052.5316234275751, 1057.603398725478, 1078.2344535379889, 1083.0793825248554, 1105.8949399807007, 11834.467448964731, 11841.94079029608, 11843.99326057286, 11846.229995050295, 11848.0, 4834.670465621982, 4838.129136755418, 4911.18965370717, 4923.556879880828, 4929.06347793918, 9916.900830991743, 9923.60305587783, 9933.453660048623, 9935.354201976848, 9963.153131983718, 5424.864378390572, 5432.891342173134, 5437.700926449004, 5438.401775804658, 5472.343318801769, 4888.486708895586, 4942.061008408391, 4946.356973593482, 4953.455119936804, 4983.177775667047, 3590.784552926833, 3665.9450108248398, 3666.2850744105176, 3891.1828516011956, 3930.037140900498, 1122.7249323915694, 1280.9103362764517, 1289.774289499334, 1295.6372215779597, 1300.14840279617, 1301.3363184775833, 1308.8298521192376, 1321.0752473268558, 1342.9059965802496, 1349.6153596225224, 7868.488548244346, 7914.485558438683, 8206.0, 8210.343172546181, 8221.54781487024, 1084.6800193942806, 1123.3652986063012, 1185.4546908422221, 1201.7476329970805, 1207.5773479282357, 6174.289956654952, 6220.688965551704, 6274.090256912722, 6283.8114856554375, 6292.2134733158355, 9919.851202231828, 10439.739006524745, 10561.313514621525, 10563.419011954262, 10564.841527376942, 10225.977402259714, 10406.791864162675, 10415.635452759117, 10444.582216711246, 10488.63647725806, 8104.351651867836, 8291.295239904555, 8303.128171542023, 8308.54303013328, 8310.792230194173, 7575.545467271982, 7741.264579864904, 7754.263344982218, 7779.805504862424, 7841.882371764314, 8120.715774947848, 9342.439453632533, 9437.867455905336, 9500.524973751422, 9642.517874106406, 9565.04349565038, 9762.0, 9793.902060979584, 9797.951010244904, 9933.15568176713, 7300.56196628204, 7366.631668416665, 7424.70301187968, 7434.962825185839, 7456.925430745623, 7108.218096009331, 7287.979682844512, 7298.416126709957, 7938.642761075881, 8042.517448953082, 5556.249906262765, 7314.817129571803, 7383.07711536058, 7474.738384156529, 7548.584827834302, 7220.566765994057, 7241.239669834592, 7314.707411703473, 7451.143118541437, 7466.887996680162, 7419.22098179705, 7421.777346679608, 7502.062242219723, 7503.212162723054, 7504.887426688713, 6798.558093723892, 6926.896096558657, 6946.583205007716, 6950.652467376712, 6966.233714291323, 6987.860242795217, 7030.753923612649, 7034.613096279753, 7042.507024508235, 7053.0830991970715], "unit": "score", "name": "mootools.html", "value": 4882.418032910901}, {"lowerIsBetter": false, "replicates": [321.29475800617644, 325.89408442256274, 325.95110733389976, 331.78931378574606, 335.25405971712956, 5315.521603055724, 6490.480761539077, 6627.801165965023, 6651.16860392451, 6788.558743681663, 6543.7055332510035, 6549.0, 6585.111010013647, 6700.899486507707, 6732.562383445073, 4722.693024953375, 4807.567318941295, 4815.109204797106, 4816.638752093607, 4855.441624213207, 4830.879228019293, 4850.223964165734, 4873.195922672755, 4909.8772530686665, 4915.6313276504225, 4548.408706868103, 4602.470715867685, 4638.489766125724, 4660.627149828023, 4755.667103302771, 4650.558196971282, 4655.092257009885, 4671.8364857229835, 4711.316859055435, 4714.175019371631, 4042.413849991764, 4047.0978640039134, 4061.208064427438, 4066.7763273019973, 4067.2272268268935, 5713.14302854571, 5899.498542623882, 6364.395382438661, 6394.8721025579225, 6441.355864413568, 4337.872153240149, 5556.805511807067, 5580.525655319303, 5632.493075623213, 5646.491815736563, 7052.153741550996, 7056.082709247765, 7061.682224299919, 7069.717211311541, 7083.433325333961, 4308.9353659695125, 4401.691881568291, 4567.086582683477, 4571.131485017836, 4588.220002599566, 7287.125544934642, 7376.225496322916, 7402.851942961111, 7407.925920740778, 7471.215522370181, 4695.460022097434, 4843.75781210938, 4893.4617192108835, 4897.363342765489, 4906.950930490721, 4872.707637541759, 4887.34020907173, 4909.656324057281, 4931.5315045070665, 4940.6788558743565, 5589.496945274945, 5782.653040817564, 5826.184334193216, 5832.358440571534, 5844.707764611752, 7117.395021423134, 7117.501774875813, 7119.288071192839, 7145.1425828900865, 7158.60627665473, 1690.3323187341177, 1718.1151706870971, 1719.3896166860584, 1721.3028723366806, 1723.5001849463515, 1096.627146770102, 1115.8605174353206, 1119.6249256499163, 1123.4045955643521, 1147.9024282936043, 6529.902051469232, 6532.346765323429, 6533.954567269309, 6535.150430444012, 6547.116139321226, 7324.96337518309, 7327.487075904635, 7335.156457007406, 7354.227806080285, 7363.337299643058, 6244.750209991549, 6489.935100649028, 6511.381418765304, 6511.804645860631, 6554.016897465418, 3884.9611503885167, 3911.9804400977814, 3914.080191155076, 3917.0, 3922.431247469101, 7682.807929801688, 7687.192844751333, 7687.923690683308, 7690.307872291521, 7706.075270967408, 7235.059442272471, 7543.283388078124, 7573.2048134946135, 7576.7348142814735, 7603.163651998276, 7652.540847549165, 7655.119661238918, 7676.232376762279, 7676.654550545127, 7715.305622494003, 7338.70645174187, 7338.853222935405, 7345.118585769742, 7349.228331025165, 7351.191368949519, 5327.440618735132, 6642.800715978528, 6921.515493915377, 7188.885722712778, 7199.568025918462, 6489.4159525641035, 7291.234420385783, 7604.163542010485, 7606.8098297543, 7607.391408687403, 4857.8299759508245, 4858.854234372974, 4868.513148685103, 4870.245112007645, 4888.291197776373, 7059.752908648173, 7364.926350736424, 7389.039424874732, 7392.593407362647, 7435.330820226206, 7126.928730712627, 7129.857402852018, 7134.678939447634, 7136.928630713626, 7147.785566433015, 6820.181578210647, 7113.75101871432, 7115.5730656160795, 7121.608311542915, 7129.928700712927, 6973.232944376013, 6982.615956122462, 6985.987031880247, 6989.231184569795, 7000.509964302653, 6448.774292899726, 7095.680694368869, 7105.82235444118, 7113.57318560888, 7115.430765538848, 6769.661516924232, 6888.655567221719, 6962.7911162665205, 6985.196702379291, 6993.335633114846], "unit": "score", "name": "prototype.html", "value": 5170.295695082098}, {"lowerIsBetter": false, "replicates": [626.7336382037635, 695.7460526907674, 717.619661579363, 742.6769355330432, 780.8516381887441, 14692.485762998298, 14921.701565968688, 14929.477468288613, 14936.402543898259, 14948.925255373722, 15348.6930261395, 15744.055356678578, 15759.369625215006, 15780.526584202493, 15788.921055394736, 575.6172145523243, 695.805174551124, 864.2740098317396, 877.8376000439907, 880.4849163239512, 713.7680253917472, 847.9448835825682, 848.2832006954145, 850.230541360067, 858.1804376820122, 891.073283784863, 891.6879092317702, 895.1138373010705, 895.2032690905099, 899.4468401932797, 722.6603496356704, 731.0277331149558, 733.457241641187, 890.9821803563925, 910.0580898769774, 655.3806652713188, 655.9770408035696, 658.4962503684685, 659.3801826283278, 666.1140682891743, 14947.925260373737, 14991.950563460563, 15049.849501504954, 15068.472603458826, 15075.472358467401, 12198.451069701796, 12243.449044792917, 12402.945749611294, 12404.25574465535, 12620.24278543281, 9964.03038760616, 9974.451405172713, 10007.449590272532, 10220.080192782685, 10325.948370258177, 3464.6535346465403, 3466.445368740989, 3466.861325546975, 3467.081223475781, 3617.1680513481783, 80550.79173812398, 80929.9767505817, 81798.0, 82227.0, 82433.0, 8043.507386033739, 9264.90735092654, 9289.814203716023, 9483.66807161746, 9613.423194608346, 20493.07781149852, 20498.38504844857, 20505.077271522816, 20508.38474845757, 20542.897285513776, 7486.401087912954, 7609.353204977529, 7658.693652253847, 7705.22947705225, 7741.0, 4129.91740165201, 4187.330027195695, 4191.91616167675, 4197.580241975839, 4198.748075115503, 3518.665726755954, 3554.9466757998653, 3559.982200089035, 3561.821908904544, 3575.4458059000895, 561.9662820230799, 635.3741564558906, 656.9605823650596, 659.4856012310405, 682.3074579302013, 488.25297295138495, 538.4749868877813, 586.4604563801313, 590.2032256453753, 618.4619381138439, 8446.493210407396, 8670.739877803677, 8683.131686831266, 8787.165219304155, 8892.828861851362, 509.900569388973, 549.3309050597871, 560.885018571192, 604.652324913168, 654.3293124547376, 3769.396896496602, 3930.508686414198, 3962.9603703963166, 3999.3800960851113, 4177.749335039907, 83113.58443207745, 83837.0, 83919.32161356861, 86175.56912215399, 87503.56248218718, 72642.27357726461, 72734.2726572738, 73477.0, 73527.63236183891, 73911.19577227463, 65581.0, 66368.0, 66428.66785666041, 66693.99959000522, 68018.0, 7459.962700186465, 8815.735527934172, 8885.37802353855, 8970.775730606787, 9030.729078127666, 44778.104437910406, 46873.0, 47157.52842471531, 47484.7625761869, 47911.5208847921, 50887.473375798785, 61182.38817611767, 61820.0, 64877.67561162353, 65001.674991626634, 10589.152867770406, 10633.787324253317, 10829.187810914056, 11045.723856903642, 11190.552377904793, 10317.896821031994, 11714.472848721998, 12282.508699652271, 12362.0, 12684.809727854274, 10547.103496202908, 10754.193435492212, 11099.112071034137, 11218.607348742755, 11365.659030229106, 11104.752141175588, 11160.832587511357, 11265.549378024787, 11612.477438515456, 12000.819987700366, 10637.414942177946, 10937.015668589866, 11097.389643569359, 11290.661280161608, 12118.636440906786, 8167.509949403055, 8695.391322607356, 8874.378793484395, 8940.910590894007, 9127.680531181377, 8962.283017358726, 9758.316917815684, 9771.75570610712, 9777.364471309342, 9889.653862114792], "unit": "score", "name": "yui.html", "value": 6730.959880397305}], "name": "dromaeo_css", "value": 7315.596853317829}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 111.0, 111.0, 110.0, 108.0, 114.0, 111.0, 111.0, 110.0], "unit": "score", "name": "ai-astar", "value": 110.6}, {"lowerIsBetter": true, "replicates": [155.0, 119.0, 174.0, 185.0, 143.0, 141.0, 138.0, 118.0, 157.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 144.9}, {"lowerIsBetter": true, "replicates": [184.0, 185.0, 184.0, 185.0, 184.0, 185.0, 183.0, 184.0, 184.0, 198.0], "unit": "score", "name": "audio-dft", "value": 185.6}, {"lowerIsBetter": true, "replicates": [81.0, 75.0, 87.0, 76.0, 56.0, 49.0, 56.0, 76.0, 100.0, 80.0], "unit": "score", "name": "audio-fft", "value": 73.6}, {"lowerIsBetter": true, "replicates": [90.0, 101.0, 140.0, 69.0, 102.0, 114.0, 143.0, 111.0, 91.0, 103.0], "unit": "score", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [86.0, 92.0, 86.0, 93.0, 86.0, 92.0, 86.0, 91.0, 86.0, 92.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 89.0}, {"lowerIsBetter": true, "replicates": [95.0, 93.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 94.6}, {"lowerIsBetter": true, "replicates": [87.0, 87.0, 88.0, 87.0, 84.0, 114.0, 139.0, 112.0, 87.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 97.2}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 53.0, 52.0, 54.0, 54.0, 54.0, 63.0, 52.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 54.7}, {"lowerIsBetter": true, "replicates": [65.0, 62.0, 70.0, 78.0, 75.0, 72.0, 69.0, 67.0, 66.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.2}, {"lowerIsBetter": true, "replicates": [82.0, 82.0, 83.0, 82.0, 83.0, 82.0, 81.0, 81.0, 81.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.0}, {"lowerIsBetter": true, "replicates": [124.0, 122.0, 125.0, 121.0, 110.0, 126.0, 125.0, 131.0, 134.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.7}, {"lowerIsBetter": true, "replicates": [127.0, 128.0, 136.0, 137.0, 137.0, 120.0, 172.0, 173.0, 137.0, 137.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 140.4}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 66.0, 67.0, 66.0, 66.0, 67.0, 67.0, 66.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.7}], "name": "kraken", "value": 1438.6000000000004}]} 05:29:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,59]]", "result": "1438.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,59]]", "result": "7315.60"}}} 05:29:33 INFO - Return code: 0 05:29:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:29:33 INFO - # TBPL SUCCESS # 05:29:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:29:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:29:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:29:33 INFO - 2016-02-05 05:29:33.691 screenresolution[1689:9746] starting screenresolution argv=screenresolution get 05:29:33 INFO - 2016-02-05 05:29:33.694 screenresolution[1689:9746] Display 0: 1600x1200x32@60 05:29:33 INFO - 2016-02-05 05:29:33.699 screenresolution[1690:9749] starting screenresolution argv=screenresolution list 05:29:33 INFO - Available Modes on Display 0 05:29:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:29:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:29:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:29:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:29:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:29:33 INFO - Intel Iris: 05:29:33 INFO - Chipset Model: Intel Iris 05:29:33 INFO - Type: GPU 05:29:33 INFO - Bus: Built-In 05:29:33 INFO - VRAM (Dynamic, Max): 1536 MB 05:29:33 INFO - Vendor: Intel (0x8086) 05:29:33 INFO - Device ID: 0x0a2e 05:29:33 INFO - Revision ID: 0x0009 05:29:33 INFO - Displays: 05:29:33 INFO - Display: 05:29:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:29:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:29:33 INFO - Display Serial Number: 1600x1200 60 05:29:33 INFO - Main Display: Yes 05:29:33 INFO - Mirror: Off 05:29:33 INFO - Online: Yes 05:29:33 INFO - Rotation: Supported 05:29:33 INFO - Return code: 0 05:29:33 INFO - Running post-action listener: _resource_record_post_action 05:29:33 INFO - Running post-run listener: _resource_record_post_run 05:29:34 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 39360000; Write bytes: 711063040; Read time: 426; Write time: 2555 05:29:34 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 402756096; Write bytes: 392705536; Read time: 29927; Write time: 753 05:29:34 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 32251392; Write bytes: 300560384; Read time: 338; Write time: 1760 05:29:34 INFO - Running post-run listener: _upload_blobber_files 05:29:34 INFO - Blob upload gear active. 05:29:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:29:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:29:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:29:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:36 INFO - (blobuploader) - INFO - Done attempting. 05:29:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:29:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:29:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:37 INFO - (blobuploader) - INFO - Done attempting. 05:29:37 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:37 INFO - Return code: 0 05:29:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec48c7a844bd9ee8ddbabfb0df5ac4a480d999f19d51d96f6d1fd9d784eb2f4e5de4627ce577287103b7b9e309c3890a6bf93b86bcc07db6d59778b3d1bfa3c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2df0c3ba110071686db3f3acb3a8cac17b7e2a11df3da8256e356915cbb2c0fcddb135706f633d6214e6a098a215e70dce58f541e6433f7832ce3f9f024743e4"} 05:29:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:37 INFO - Contents: 05:29:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec48c7a844bd9ee8ddbabfb0df5ac4a480d999f19d51d96f6d1fd9d784eb2f4e5de4627ce577287103b7b9e309c3890a6bf93b86bcc07db6d59778b3d1bfa3c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2df0c3ba110071686db3f3acb3a8cac17b7e2a11df3da8256e356915cbb2c0fcddb135706f633d6214e6a098a215e70dce58f541e6433f7832ce3f9f024743e4"} 05:29:37 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:37 INFO - Copying logs to upload dir... 05:29:37 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.401207 ========= master_lag: 2.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-02-05 05:29:39.906861) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-05 05:29:39.911544) ========= 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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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/ec48c7a844bd9ee8ddbabfb0df5ac4a480d999f19d51d96f6d1fd9d784eb2f4e5de4627ce577287103b7b9e309c3890a6bf93b86bcc07db6d59778b3d1bfa3c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2df0c3ba110071686db3f3acb3a8cac17b7e2a11df3da8256e356915cbb2c0fcddb135706f633d6214e6a098a215e70dce58f541e6433f7832ce3f9f024743e4"} build_url:https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011174 build_url: 'https://queue.taskcluster.net/v1/task/CPf4_temQx-yEnKtbNlqJA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec48c7a844bd9ee8ddbabfb0df5ac4a480d999f19d51d96f6d1fd9d784eb2f4e5de4627ce577287103b7b9e309c3890a6bf93b86bcc07db6d59778b3d1bfa3c1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2df0c3ba110071686db3f3acb3a8cac17b7e2a11df3da8256e356915cbb2c0fcddb135706f633d6214e6a098a215e70dce58f541e6433f7832ce3f9f024743e4"}' ========= master_lag: 0.21 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-05 05:29:40.135923) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-02-05 05:29:40.136224) ========= 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.KAEzgxrYoN/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.QZ18L1fgTK/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005224 ========= master_lag: 4.49 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-02-05 05:29:44.634454) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-05 05:29:44.634785) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-05 05:29:44.710359) ========= ========= Total master_lag: 14.40 =========