builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0051 starttime: 1460064590.54 results: success (0) buildid: 20160407123345 builduid: 066cf6257da244c0a551ddaa5a4dd073 revision: e787670d68a4fdb7affc82be7a985657931e0776 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 14:29:50.538611) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 14:29:50.539070) ========= ========= Started set props: basedir (results: 0, elapsed: 5 secs) (at 2016-04-07 14:29:50.539359) ========= 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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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.005936 basedir: '/builds/slave/test' ========= master_lag: 5.85 ========= ========= Finished set props: basedir (results: 0, elapsed: 5 secs) (at 2016-04-07 14:29:56.398620) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 14:29:56.398945) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 14:29:57.235124) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-04-07 14:29:57.235376) ========= 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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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.005295 ========= master_lag: 5.81 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-04-07 14:30:03.050677) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 14:30:03.050972) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 14:30:03.051313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 14:30:03.051589) ========= 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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-07 14:30:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 49.5M=0s 2016-04-07 14:30:04 (49.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.200373 ========= master_lag: 1.40 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 14:30:04.652673) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-04-07 14:30:04.652992) ========= 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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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.020066 ========= master_lag: 5.35 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-04-07 14:30:10.024155) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2016-04-07 14:30:10.024519) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e787670d68a4fdb7affc82be7a985657931e0776 --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 e787670d68a4fdb7affc82be7a985657931e0776 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-07 14:30:10,125 truncating revision to first 12 chars 2016-04-07 14:30:10,125 Setting DEBUG logging. 2016-04-07 14:30:10,125 attempt 1/10 2016-04-07 14:30:10,125 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e787670d68a4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-07 14:30:11,865 unpacking tar archive at: mozilla-central-e787670d68a4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.992761 ========= master_lag: 7.75 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2016-04-07 14:30:19.764815) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 14:30:19.765628) ========= echo e787670d68a4fdb7affc82be7a985657931e0776 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'e787670d68a4fdb7affc82be7a985657931e0776'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 e787670d68a4fdb7affc82be7a985657931e0776 program finished with exit code 0 elapsedTime=0.005052 script_repo_revision: 'e787670d68a4fdb7affc82be7a985657931e0776' ========= master_lag: 0.37 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 14:30:20.145473) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 14:30:20.145754) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 14:30:20.670616) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-07 14:30:20.670919) ========= /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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:30:20 INFO - MultiFileLogger online at 20160407 14:30:20 in /builds/slave/test 14:30:20 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 14:30:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:30:20 INFO - {'append_to_log': False, 14:30:20 INFO - 'base_work_dir': '/builds/slave/test', 14:30:20 INFO - 'blob_upload_branch': 'Firefox', 14:30:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:30:20 INFO - 'branch': 'Firefox', 14:30:20 INFO - 'buildbot_json_path': 'buildprops.json', 14:30:20 INFO - 'config_files': ('talos/mac_config.py',), 14:30:20 INFO - 'default_actions': ('clobber', 14:30:20 INFO - 'read-buildbot-config', 14:30:20 INFO - 'download-and-extract', 14:30:20 INFO - 'populate-webroot', 14:30:20 INFO - 'create-virtualenv', 14:30:20 INFO - 'install', 14:30:20 INFO - 'run-tests'), 14:30:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:30:20 INFO - 'download_minidump_stackwalk': True, 14:30:20 INFO - 'download_symbols': 'ondemand', 14:30:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:30:20 INFO - 'tooltool.py': '/tools/tooltool.py', 14:30:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:30:20 INFO - '/tools/misc-python/virtualenv.py')}, 14:30:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:30:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:30:20 INFO - 'installer_path': 'installer.exe', 14:30:20 INFO - 'log_level': 'info', 14:30:20 INFO - 'log_name': 'talos', 14:30:20 INFO - 'log_to_console': True, 14:30:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:30:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:30:20 INFO - 'opt_config_files': (), 14:30:20 INFO - 'pip_index': False, 14:30:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:30:20 INFO - 'cmd': ('bash', 14:30:20 INFO - '-c', 14:30:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:30:20 INFO - 'enabled': True, 14:30:20 INFO - 'halt_on_failure': False, 14:30:20 INFO - 'name': 'check_screen_resolution'},), 14:30:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:30:20 INFO - 'cmd': ('bash', 14:30:20 INFO - '-c', 14:30:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:30:20 INFO - 'enabled': True, 14:30:20 INFO - 'halt_on_failure': False, 14:30:20 INFO - 'name': 'check_screen_resolution'},), 14:30:20 INFO - 'run_cmd_checks_enabled': True, 14:30:20 INFO - 'sps_profile': False, 14:30:20 INFO - 'sps_profile_interval': 0, 14:30:20 INFO - 'suite': 'dromaeojs', 14:30:20 INFO - 'system_bits': '32', 14:30:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:30:20 INFO - 'title': 't-yosemite-r7-0051', 14:30:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:30:20 INFO - 'use_talos_json': True, 14:30:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:30:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:30:20 INFO - 'work_dir': 'build'} 14:30:20 INFO - ##### 14:30:20 INFO - ##### Running clobber step. 14:30:20 INFO - ##### 14:30:20 INFO - Running pre-action listener: _resource_record_pre_action 14:30:20 INFO - Running main action method: clobber 14:30:20 INFO - rmtree: /builds/slave/test/build 14:30:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:30:22 INFO - Running post-action listener: _resource_record_post_action 14:30:22 INFO - ##### 14:30:22 INFO - ##### Running read-buildbot-config step. 14:30:22 INFO - ##### 14:30:22 INFO - Running pre-action listener: _resource_record_pre_action 14:30:22 INFO - Running main action method: read_buildbot_config 14:30:22 INFO - Using buildbot properties: 14:30:22 INFO - { 14:30:22 INFO - "project": "", 14:30:22 INFO - "product": "firefox", 14:30:22 INFO - "script_repo_revision": "production", 14:30:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:30:22 INFO - "repository": "", 14:30:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:30:22 INFO - "buildid": "20160407123345", 14:30:22 INFO - "pgo_build": "False", 14:30:22 INFO - "basedir": "/builds/slave/test", 14:30:22 INFO - "buildnumber": 198, 14:30:22 INFO - "slavename": "t-yosemite-r7-0051", 14:30:22 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:30:22 INFO - "platform": "yosemite_r7", 14:30:22 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:30:22 INFO - "branch": "mozilla-central", 14:30:22 INFO - "repo_path": "mozilla-central", 14:30:22 INFO - "revision": "e787670d68a4fdb7affc82be7a985657931e0776", 14:30:22 INFO - "stage_platform": "macosx64", 14:30:22 INFO - "builduid": "066cf6257da244c0a551ddaa5a4dd073", 14:30:22 INFO - "slavebuilddir": "test" 14:30:22 INFO - } 14:30:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 14:30:22 INFO - Running post-action listener: _resource_record_post_action 14:30:22 INFO - ##### 14:30:22 INFO - ##### Running download-and-extract step. 14:30:22 INFO - ##### 14:30:22 INFO - Running pre-action listener: _resource_record_pre_action 14:30:22 INFO - Running main action method: download_and_extract 14:30:22 INFO - mkdir: /builds/slave/test/build/tests 14:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:22 INFO - https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:30:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 14:30:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 14:30:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 14:30:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 14:30:25 INFO - Downloaded 1199 bytes. 14:30:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 14:30:25 INFO - Using the following test package requirements: 14:30:25 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 14:30:25 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:30:25 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 14:30:25 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:30:25 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 14:30:25 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:30:25 INFO - u'jsshell-mac.zip'], 14:30:25 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:30:25 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 14:30:25 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 14:30:25 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:30:25 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 14:30:25 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:30:25 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 14:30:25 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:30:25 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 14:30:25 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 14:30:25 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 14:30:25 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:30:25 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')]} 14:30:25 INFO - https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:30:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:30:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:30:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:30:26 INFO - Downloaded 17971456 bytes. 14:30:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:30:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:30:29 INFO - Return code: 0 14:30:29 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:29 INFO - https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:30:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:30:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:30:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 14:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:30:30 INFO - Downloaded 17971456 bytes. 14:30:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:30:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:30:34 INFO - Return code: 0 14:30:34 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')]} 14:30:34 INFO - https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:30:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 14:30:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 14:30:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 14:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:30:35 INFO - Downloaded 10847693 bytes. 14:30:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:30:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:30:36 INFO - Return code: 0 14:30: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')]} 14:30:36 INFO - https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:30:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 14:30:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 14:30:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 14:30:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 14:30:40 INFO - Downloaded 108885714 bytes. 14:30:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 14:30:40 INFO - mkdir: /builds/slave/test/properties 14:30:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:30:40 INFO - Writing to file /builds/slave/test/properties/build_url 14:30:40 INFO - Contents: 14:30:40 INFO - build_url:https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 14:30:41 INFO - Running post-action listener: _resource_record_post_action 14:30:41 INFO - Running post-action listener: set_extra_try_arguments 14:30:41 INFO - ##### 14:30:41 INFO - ##### Running populate-webroot step. 14:30:41 INFO - ##### 14:30:41 INFO - Running pre-action listener: _resource_record_pre_action 14:30:41 INFO - Running main action method: populate_webroot 14:30:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:30:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:30:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:30:41 INFO - '--tppagecycles', 14:30:41 INFO - '7'], 14:30:41 INFO - 'tests': ['tsvgm']}}, 14:30:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:30:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:30:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:30:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:30:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:41 INFO - 'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:30:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:30:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:41 INFO - 'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['damp', 'tps']}, 14:30:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:41 INFO - 'tests': ['damp', 'tps']}, 14:30:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['dromaeo_dom']}, 14:30:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:30:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['a11yr', 14:30:41 INFO - 'ts_paint', 14:30:41 INFO - 'tpaint', 14:30:41 INFO - 'sessionrestore', 14:30:41 INFO - 'sessionrestore_no_auto_restore', 14:30:41 INFO - 'tabpaint']}, 14:30:41 INFO - 'other-e10s': {'tests': ['a11yr', 14:30:41 INFO - 'ts_paint', 14:30:41 INFO - 'tpaint', 14:30:41 INFO - 'sessionrestore', 14:30:41 INFO - 'sessionrestore_no_auto_restore', 14:30:41 INFO - 'tabpaint']}, 14:30:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:30:41 INFO - 'ts_paint', 14:30:41 INFO - 'tpaint', 14:30:41 INFO - 'sessionrestore', 14:30:41 INFO - 'sessionrestore_no_auto_restore', 14:30:41 INFO - 'tabpaint']}, 14:30:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:30:41 INFO - 'ts_paint', 14:30:41 INFO - 'tpaint', 14:30:41 INFO - 'sessionrestore', 14:30:41 INFO - 'sessionrestore_no_auto_restore', 14:30:41 INFO - 'tabpaint']}, 14:30:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['a11yr', 14:30:41 INFO - 'ts_paint', 14:30:41 INFO - 'tpaint', 14:30:41 INFO - 'sessionrestore', 14:30:41 INFO - 'sessionrestore_no_auto_restore', 14:30:41 INFO - 'tabpaint']}, 14:30:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['a11yr', 14:30:41 INFO - 'ts_paint', 14:30:41 INFO - 'tpaint', 14:30:41 INFO - 'sessionrestore', 14:30:41 INFO - 'sessionrestore_no_auto_restore', 14:30:41 INFO - 'tabpaint']}, 14:30:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['tsvgx', 14:30:41 INFO - 'tsvgr_opacity', 14:30:41 INFO - 'tart', 14:30:41 INFO - 'tscrollx', 14:30:41 INFO - 'cart']}, 14:30:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:30:41 INFO - 'tsvgr_opacity', 14:30:41 INFO - 'tart', 14:30:41 INFO - 'tscrollx', 14:30:41 INFO - 'cart']}, 14:30:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:41 INFO - 'talos_options': ['--disable-e10s'], 14:30:41 INFO - 'tests': ['tp5o']}, 14:30:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:41 INFO - 'tests': ['tp5o']}, 14:30:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:41 INFO - 'talos_options': ['--disable-e10s', 14:30:41 INFO - '--xperf_path', 14:30:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:30:41 INFO - 'tests': ['tp5n']}, 14:30:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:41 INFO - 'talos_options': ['--xperf_path', 14:30:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:30:41 INFO - 'tests': ['tp5n']}}, 14:30:41 INFO - 'talos.zip': {'path': '', 14:30:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:30:41 INFO - Running post-action listener: _resource_record_post_action 14:30:41 INFO - ##### 14:30:41 INFO - ##### Running create-virtualenv step. 14:30:41 INFO - ##### 14:30:41 INFO - Running pre-action listener: _resource_record_pre_action 14:30:41 INFO - Running main action method: create_virtualenv 14:30:41 INFO - Creating virtualenv /builds/slave/test/build/venv 14:30:41 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 14:30:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:30:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:30:41 INFO - Using real prefix '/tools/python27' 14:30:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:30:42 INFO - Installing distribute.............................................................................................................................................................................................done. 14:30:45 INFO - Installing pip.................done. 14:30:45 INFO - Return code: 0 14:30:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:30: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')]} 14:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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')]} 14:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:45 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 0x109624a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109634420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd324a9640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, '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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:30:45 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 14:30:45 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 14:30:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:30:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:45 INFO - 'HOME': '/Users/cltbld', 14:30:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:45 INFO - 'LOGNAME': 'cltbld', 14:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:45 INFO - 'MOZ_NO_REMOTE': '1', 14:30:45 INFO - 'NO_EM_RESTART': '1', 14:30:45 INFO - 'PAGER': '/bin/cat', 14:30:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:45 INFO - 'PWD': '/builds/slave/test', 14:30:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:45 INFO - 'SHELL': '/bin/bash', 14:30:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:30:45 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:30:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:45 INFO - 'USER': 'cltbld', 14:30:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:45 INFO - 'XPC_FLAGS': '0x0', 14:30:45 INFO - 'XPC_SERVICE_NAME': '0', 14:30:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:45 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 14:30:45 INFO - Cleaning up... 14:30:45 INFO - Return code: 0 14:30:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:30: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')]} 14:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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')]} 14:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:45 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 0x109624a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109634420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd324a9640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, '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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:30:45 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 14:30:45 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 14:30:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:30:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:45 INFO - 'HOME': '/Users/cltbld', 14:30:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:45 INFO - 'LOGNAME': 'cltbld', 14:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:45 INFO - 'MOZ_NO_REMOTE': '1', 14:30:45 INFO - 'NO_EM_RESTART': '1', 14:30:45 INFO - 'PAGER': '/bin/cat', 14:30:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:45 INFO - 'PWD': '/builds/slave/test', 14:30:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:45 INFO - 'SHELL': '/bin/bash', 14:30:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:30:45 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:30:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:45 INFO - 'USER': 'cltbld', 14:30:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:45 INFO - 'XPC_FLAGS': '0x0', 14:30:45 INFO - 'XPC_SERVICE_NAME': '0', 14:30:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:45 INFO - Downloading/unpacking psutil>=0.7.1 14:30:45 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 14:30:45 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 14:30:45 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 14:30:45 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 14:30:45 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 14:30:45 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 14:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:30:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:30:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:30:48 INFO - Installing collected packages: psutil 14:30:48 INFO - Running setup.py install for psutil 14:30:48 INFO - building 'psutil._psutil_osx' extension 14:30:48 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 14:30:48 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 14:30:48 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 14:30:49 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 14:30:49 INFO - building 'psutil._psutil_posix' extension 14:30:49 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 14:30:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:30:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:30:49 INFO - ^ 14:30:49 INFO - 1 warning generated. 14:30:49 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 14:30:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:30:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:30:49 INFO - Successfully installed psutil 14:30:49 INFO - Cleaning up... 14:30:49 INFO - Return code: 0 14:30:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109624a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109634420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd324a9640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, '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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:30:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 14:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 14:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:49 INFO - 'HOME': '/Users/cltbld', 14:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:49 INFO - 'LOGNAME': 'cltbld', 14:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:49 INFO - 'MOZ_NO_REMOTE': '1', 14:30:49 INFO - 'NO_EM_RESTART': '1', 14:30:49 INFO - 'PAGER': '/bin/cat', 14:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:49 INFO - 'PWD': '/builds/slave/test', 14:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:49 INFO - 'SHELL': '/bin/bash', 14:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:30:49 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:49 INFO - 'USER': 'cltbld', 14:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:49 INFO - 'XPC_FLAGS': '0x0', 14:30:49 INFO - 'XPC_SERVICE_NAME': '0', 14:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:49 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 14:30:49 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 14:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:30:52 INFO - Installing collected packages: mozsystemmonitor 14:30:52 INFO - Running setup.py install for mozsystemmonitor 14:30:52 INFO - Successfully installed mozsystemmonitor 14:30:52 INFO - Cleaning up... 14:30:52 INFO - Return code: 0 14:30:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:52 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 0x109624a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109634420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd324a9640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, '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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:30:52 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 14:30:52 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 14:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:52 INFO - 'HOME': '/Users/cltbld', 14:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:52 INFO - 'LOGNAME': 'cltbld', 14:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:52 INFO - 'MOZ_NO_REMOTE': '1', 14:30:52 INFO - 'NO_EM_RESTART': '1', 14:30:52 INFO - 'PAGER': '/bin/cat', 14:30:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:52 INFO - 'PWD': '/builds/slave/test', 14:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:52 INFO - 'SHELL': '/bin/bash', 14:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:30:52 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:52 INFO - 'USER': 'cltbld', 14:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:52 INFO - 'XPC_FLAGS': '0x0', 14:30:52 INFO - 'XPC_SERVICE_NAME': '0', 14:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:52 INFO - Downloading/unpacking blobuploader==1.2.4 14:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:54 INFO - Downloading blobuploader-1.2.4.tar.gz 14:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:30:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:30:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30: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 14:30:56 INFO - Downloading docopt-0.6.1.tar.gz 14:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:30:56 INFO - Installing collected packages: blobuploader, requests, docopt 14:30:56 INFO - Running setup.py install for blobuploader 14:30:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:30:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:30:56 INFO - Running setup.py install for requests 14:30:56 INFO - Running setup.py install for docopt 14:30:57 INFO - Successfully installed blobuploader requests docopt 14:30:57 INFO - Cleaning up... 14:30:57 INFO - Return code: 0 14:30:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:57 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 0x109624a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109634420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd324a9640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, '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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:30:57 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 14:30:57 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 14:30:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:30:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:57 INFO - 'HOME': '/Users/cltbld', 14:30:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:57 INFO - 'LOGNAME': 'cltbld', 14:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:57 INFO - 'MOZ_NO_REMOTE': '1', 14:30:57 INFO - 'NO_EM_RESTART': '1', 14:30:57 INFO - 'PAGER': '/bin/cat', 14:30:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:57 INFO - 'PWD': '/builds/slave/test', 14:30:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:57 INFO - 'SHELL': '/bin/bash', 14:30:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:30:57 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:30:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:57 INFO - 'USER': 'cltbld', 14:30:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:57 INFO - 'XPC_FLAGS': '0x0', 14:30:57 INFO - 'XPC_SERVICE_NAME': '0', 14:30:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:57 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-28TSFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:57 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-4yyOi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:57 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-xRLQzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:57 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-O239u6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:57 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-QVPJoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:57 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-bLg18K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:58 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-fGxgkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:58 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-w28HAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:58 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-slwcqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:58 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-rYSVKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:58 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-86uzSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:58 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-_GGaGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:58 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-_mbawT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:58 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-m6UFmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:58 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-fQ0IM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:59 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-VVniE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:59 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-SSnE60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:30:59 INFO - Running setup.py install for manifestparser 14:30:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:30:59 INFO - Running setup.py install for mozcrash 14:30:59 INFO - Running setup.py install for mozdebug 14:30:59 INFO - Running setup.py install for mozdevice 14:30:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:30:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:30:59 INFO - Running setup.py install for mozfile 14:30:59 INFO - Running setup.py install for mozhttpd 14:31:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:31:00 INFO - Running setup.py install for mozinfo 14:31:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:31:00 INFO - Running setup.py install for mozInstall 14:31:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:31:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:31:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:31:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:31:00 INFO - Running setup.py install for mozleak 14:31:00 INFO - Running setup.py install for mozlog 14:31:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:31:00 INFO - Running setup.py install for moznetwork 14:31:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:31:00 INFO - Running setup.py install for mozprocess 14:31:01 INFO - Running setup.py install for mozprofile 14:31:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:31:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:31:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:31:01 INFO - Running setup.py install for mozrunner 14:31:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:31:01 INFO - Running setup.py install for mozscreenshot 14:31:01 INFO - Running setup.py install for moztest 14:31:01 INFO - Running setup.py install for mozversion 14:31:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:31:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:31:01 INFO - Cleaning up... 14:31:01 INFO - Return code: 0 14:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:01 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 0x109624a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109634420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd324a9640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, '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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:31:01 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 14:31:01 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 14:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:01 INFO - 'HOME': '/Users/cltbld', 14:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:01 INFO - 'LOGNAME': 'cltbld', 14:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:01 INFO - 'MOZ_NO_REMOTE': '1', 14:31:01 INFO - 'NO_EM_RESTART': '1', 14:31:01 INFO - 'PAGER': '/bin/cat', 14:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:01 INFO - 'PWD': '/builds/slave/test', 14:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:01 INFO - 'SHELL': '/bin/bash', 14:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:31:01 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:01 INFO - 'USER': 'cltbld', 14:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:01 INFO - 'XPC_FLAGS': '0x0', 14:31:01 INFO - 'XPC_SERVICE_NAME': '0', 14:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:31:02 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-7zMMlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:31:02 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)) 14:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:31:02 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-wSNUhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:31:02 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-dawv7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:31:02 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)) 14:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:31:02 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-8lxvmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:31:02 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)) 14:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:31:02 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-UUtoOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:31:02 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)) 14:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:31:02 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-78Zkq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:31:02 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)) 14:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:31:02 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-RIZ153-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:31:02 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)) 14:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:31:02 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-fc0ptr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:31:02 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)) 14:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:31:02 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-yX9DyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:31:03 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)) 14:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:31:03 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-cl6V9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:31:03 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)) 14:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:31:03 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-ToJvGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:31:03 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)) 14:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:31:03 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-vdbiZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:31:03 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)) 14:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:31:03 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-pBkxPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:31:03 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)) 14:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:31:03 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-G4m2o7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:31:03 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)) 14:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:31:03 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-FLbilN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:31:03 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)) 14:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:31:03 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-UjlfLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:31:03 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)) 14:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:31:03 INFO - Running setup.py (path:/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/pip-_kNyoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:31:03 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)) 14:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:31:03 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)) 14:31:03 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)) 14:31:03 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)) 14:31:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:31:03 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 14:31:03 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 14:31:03 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 14:31:03 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 14:31:03 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 14:31:03 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 14:31:06 INFO - Downloading blessings-1.6.tar.gz 14:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:31:06 INFO - Installing collected packages: blessings 14:31:06 INFO - Running setup.py install for blessings 14:31:06 INFO - Successfully installed blessings 14:31:06 INFO - Cleaning up... 14:31:06 INFO - Return code: 0 14:31:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:31:06 INFO - Reading from file tmpfile_stdout 14:31:06 INFO - Current package versions: 14:31:06 INFO - blessings == 1.6 14:31:06 INFO - blobuploader == 1.2.4 14:31:06 INFO - docopt == 0.6.1 14:31:06 INFO - manifestparser == 1.1 14:31:06 INFO - mozInstall == 1.12 14:31:06 INFO - mozcrash == 0.17 14:31:06 INFO - mozdebug == 0.1 14:31:06 INFO - mozdevice == 0.48 14:31:06 INFO - mozfile == 1.2 14:31:06 INFO - mozhttpd == 0.7 14:31:06 INFO - mozinfo == 0.9 14:31:06 INFO - mozleak == 0.1 14:31:06 INFO - mozlog == 3.1 14:31:06 INFO - moznetwork == 0.27 14:31:06 INFO - mozprocess == 0.22 14:31:06 INFO - mozprofile == 0.28 14:31:06 INFO - mozrunner == 6.11 14:31:06 INFO - mozscreenshot == 0.1 14:31:06 INFO - mozsystemmonitor == 0.0 14:31:06 INFO - moztest == 0.7 14:31:06 INFO - mozversion == 1.4 14:31:06 INFO - psutil == 3.1.1 14:31:06 INFO - requests == 1.2.3 14:31:06 INFO - wsgiref == 0.1.2 14:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:31: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')]} 14:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31: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')]} 14:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:06 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 0x109624a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109634420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd324a9640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, '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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:31:06 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 14:31:06 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 14:31:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:31:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:06 INFO - 'HOME': '/Users/cltbld', 14:31:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:06 INFO - 'LOGNAME': 'cltbld', 14:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:06 INFO - 'MOZ_NO_REMOTE': '1', 14:31:06 INFO - 'NO_EM_RESTART': '1', 14:31:06 INFO - 'PAGER': '/bin/cat', 14:31:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:06 INFO - 'PWD': '/builds/slave/test', 14:31:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:06 INFO - 'SHELL': '/bin/bash', 14:31:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:31:06 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:31:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:06 INFO - 'USER': 'cltbld', 14:31:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:06 INFO - 'XPC_FLAGS': '0x0', 14:31:06 INFO - 'XPC_SERVICE_NAME': '0', 14:31:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:07 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)) 14:31:07 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)) 14:31:07 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)) 14:31:07 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)) 14:31:07 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)) 14:31:07 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)) 14:31:07 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)) 14:31:07 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)) 14:31:07 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)) 14:31:07 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)) 14:31:07 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)) 14:31:07 INFO - Cleaning up... 14:31:07 INFO - Return code: 0 14:31:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:31:07 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')]} 14:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:07 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')]} 14:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:07 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 0x109624a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109634420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd324a9640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, '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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:31:07 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 14:31:07 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 14:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:07 INFO - 'HOME': '/Users/cltbld', 14:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:07 INFO - 'LOGNAME': 'cltbld', 14:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:07 INFO - 'MOZ_NO_REMOTE': '1', 14:31:07 INFO - 'NO_EM_RESTART': '1', 14:31:07 INFO - 'PAGER': '/bin/cat', 14:31:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:07 INFO - 'PWD': '/builds/slave/test', 14:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:07 INFO - 'SHELL': '/bin/bash', 14:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:31:07 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:07 INFO - 'USER': 'cltbld', 14:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:07 INFO - 'XPC_FLAGS': '0x0', 14:31:07 INFO - 'XPC_SERVICE_NAME': '0', 14:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:07 INFO - Downloading/unpacking jsonschema 14:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:31:09 INFO - Downloading/unpacking functools32 (from jsonschema) 14:31:09 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 14:31:09 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 14:31:09 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 14:31:09 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 14:31:09 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 14:31:09 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 14:31:10 INFO - Downloading functools32-3.2.3-2.tar.gz 14:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:31:10 INFO - warning: no files found matching '*.txt' 14:31:10 INFO - no previously-included directories found matching 'build' 14:31:10 INFO - no previously-included directories found matching 'dist' 14:31:10 INFO - no previously-included directories found matching '.git*' 14:31:10 INFO - Installing collected packages: jsonschema, functools32 14:31:10 INFO - Running setup.py install for functools32 14:31:10 INFO - warning: no files found matching '*.txt' 14:31:10 INFO - no previously-included directories found matching 'build' 14:31:10 INFO - no previously-included directories found matching 'dist' 14:31:10 INFO - no previously-included directories found matching '.git*' 14:31:10 INFO - Successfully installed jsonschema functools32 14:31:10 INFO - Cleaning up... 14:31:10 INFO - Return code: 0 14:31:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:31:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109624a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109634420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd324a9640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, '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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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 14:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:10 INFO - 'HOME': '/Users/cltbld', 14:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:10 INFO - 'LOGNAME': 'cltbld', 14:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:10 INFO - 'MOZ_NO_REMOTE': '1', 14:31:10 INFO - 'NO_EM_RESTART': '1', 14:31:10 INFO - 'PAGER': '/bin/cat', 14:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:10 INFO - 'PWD': '/builds/slave/test', 14:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:10 INFO - 'SHELL': '/bin/bash', 14:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:31:10 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:10 INFO - 'USER': 'cltbld', 14:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:10 INFO - 'XPC_FLAGS': '0x0', 14:31:10 INFO - 'XPC_SERVICE_NAME': '0', 14:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:31:10 INFO - Downloading/unpacking flake8 14:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:31:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:31:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:31: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 14:31: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 14:31: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 14:31: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 14:31: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 14:31: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 14:31:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:31:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:31: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 14:31: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 14:31: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 14:31: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 14:31: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 14:31: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 14:31:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:31:14 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 14:31:14 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 14:31:14 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 14:31:14 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 14:31:14 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 14:31:14 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 14:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:31:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:31:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:31:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:31:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:31:14 INFO - no previously-included directories found matching 'docs/_build' 14:31:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:31:14 INFO - Running setup.py install for pep8 14:31:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:31:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:31:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:31:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:31:14 INFO - no previously-included directories found matching 'docs/_build' 14:31:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:31:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:31:14 INFO - Cleaning up... 14:31:14 INFO - Return code: 0 14:31:14 INFO - Running post-action listener: _resource_record_post_action 14:31:14 INFO - Running post-action listener: _start_resource_monitoring 14:31:14 INFO - Starting resource monitoring. 14:31:14 INFO - ##### 14:31:14 INFO - ##### Running install step. 14:31:14 INFO - ##### 14:31:14 INFO - Running pre-action listener: _resource_record_pre_action 14:31:14 INFO - Running main action method: install 14:31:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:31:15 INFO - Reading from file tmpfile_stdout 14:31:15 INFO - Detecting whether we're running mozinstall >=1.0... 14:31:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:31:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:31:15 INFO - Reading from file tmpfile_stdout 14:31:15 INFO - Output received: 14:31:15 INFO - Usage: mozinstall [options] installer 14:31:15 INFO - Options: 14:31:15 INFO - -h, --help show this help message and exit 14:31:15 INFO - -d DEST, --destination=DEST 14:31:15 INFO - Directory to install application into. [default: 14:31:15 INFO - "/builds/slave/test"] 14:31:15 INFO - --app=APP Application being installed. [default: firefox] 14:31:15 INFO - mkdir: /builds/slave/test/build/application 14:31:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:31:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:31:49 INFO - Reading from file tmpfile_stdout 14:31:49 INFO - Output received: 14:31:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:31:49 INFO - Running post-action listener: _resource_record_post_action 14:31:49 INFO - ##### 14:31:49 INFO - ##### Running run-tests step. 14:31:49 INFO - ##### 14:31:49 INFO - Running pre-action listener: _resource_record_pre_action 14:31:49 INFO - Running main action method: run_tests 14:31:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:31:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:31:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:31:49 INFO - 2016-04-07 14:31:49.090 screenresolution[2274:21025] starting screenresolution argv=screenresolution get 14:31:49 INFO - 2016-04-07 14:31:49.093 screenresolution[2274:21025] Display 0: 1600x1200x32@60 14:31:49 INFO - 2016-04-07 14:31:49.099 screenresolution[2275:21028] starting screenresolution argv=screenresolution list 14:31:49 INFO - Available Modes on Display 0 14:31:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:31:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:31:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:31:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:31:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:31:49 INFO - Intel Iris: 14:31:49 INFO - Chipset Model: Intel Iris 14:31:49 INFO - Type: GPU 14:31:49 INFO - Bus: Built-In 14:31:49 INFO - VRAM (Dynamic, Max): 1536 MB 14:31:49 INFO - Vendor: Intel (0x8086) 14:31:49 INFO - Device ID: 0x0a2e 14:31:49 INFO - Revision ID: 0x0009 14:31:49 INFO - Displays: 14:31:49 INFO - Display: 14:31:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:31:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:31:49 INFO - Display Serial Number: 1600x1200 60 14:31:49 INFO - Main Display: Yes 14:31:49 INFO - Mirror: Off 14:31:49 INFO - Online: Yes 14:31:49 INFO - Rotation: Supported 14:31:49 INFO - Return code: 0 14:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:31:49 INFO - Python 2.7.3 14:31:49 INFO - Return code: 0 14:31:49 INFO - grabbing minidump binary from tooltool 14:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:31:49 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 0x1094c3e00>, '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 0x1092ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094beb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:31:49 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 14:31:49 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 14:31:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:31:49 INFO - Return code: 0 14:31:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:31:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:31:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:31:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:31:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:31:50 INFO - Return code: 0 14:31:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0051', '--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 14:31:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0051 --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 14:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FUR2TK7Qt0/Render', 14:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:31:50 INFO - 'HOME': '/Users/cltbld', 14:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:31:50 INFO - 'LOGNAME': 'cltbld', 14:31:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:31:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:50 INFO - 'MOZ_NO_REMOTE': '1', 14:31:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:31:50 INFO - 'NO_EM_RESTART': '1', 14:31:50 INFO - 'PAGER': '/bin/cat', 14:31:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:50 INFO - 'PWD': '/builds/slave/test', 14:31:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:31:50 INFO - 'SHELL': '/bin/bash', 14:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bOPQ3xJ3GS/Listeners', 14:31:50 INFO - 'TMPDIR': '/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/', 14:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:31:50 INFO - 'USER': 'cltbld', 14:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:50 INFO - 'XPC_FLAGS': '0x0', 14:31:50 INFO - 'XPC_SERVICE_NAME': '0', 14:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:31:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0051', '--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 14:31:50 INFO - mozversion application_buildid: 20160407123345 14:31:50 INFO - mozversion application_changeset: e787670d68a4fdb7affc82be7a985657931e0776 14:31:50 INFO - mozversion application_display_name: Nightly 14:31:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:31:50 INFO - mozversion application_name: Firefox 14:31:50 INFO - mozversion application_remotingname: firefox 14:31:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:31:50 INFO - mozversion application_vendor: Mozilla 14:31:50 INFO - mozversion application_version: 48.0a1 14:31:50 INFO - mozversion platform_buildid: 20160407123345 14:31:50 INFO - mozversion platform_changeset: e787670d68a4fdb7affc82be7a985657931e0776 14:31:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:31:50 INFO - mozversion platform_version: 48.0a1 14:31:50 INFO - using testdate: 1460064710 14:31:50 INFO - actual date: 1460064710 14:31:50 INFO - starting webserver on 'localhost:57262' 14:31:50 INFO - SUITE-START | Running 2 tests 14:31:50 INFO - TEST-START | dromaeo_css 14:31:50 INFO - operating with platform_type : mac_ 14:31:50 INFO - Initialising browser for dromaeo_css test... 14:31:50 INFO - TEST-INFO | started process 2293 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/tmpppU2dA/profile http://localhost:57262/getInfo.html) 14:31:52 INFO - PROCESS | 2293 | __metrics Screen width/height:1600/1200 14:31:52 INFO - PROCESS | 2293 | colorDepth:24 14:31:52 INFO - PROCESS | 2293 | Browser inner width/height: 1024/689 14:31:52 INFO - PROCESS | 2293 | __metrics 14:31:53 INFO - PROCESS | 2293 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:31:53 ERROR - PROCESS | 2293 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:31:53 INFO - TEST-INFO | 2293: exit 0 14:31:53 INFO - Browser initialized. 14:31:53 INFO - Running cycle 1/1 for dromaeo_css test... 14:31:53 INFO - TEST-INFO | started process 2294 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/tmpppU2dA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:32:05 INFO - PROCESS | 2294 | RSS: Main: 237023232 14:32:05 INFO - PROCESS | 2294 | 14:32:56 INFO - PROCESS | 2294 | 1460064776776 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] 14:35:07 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57262/tests/dromaeo/cssquery-ext.html) 14:35:08 INFO - PROCESS | 2294 | RSS: Main: 360591360 14:35:08 INFO - PROCESS | 2294 | 14:38:11 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-ext.html (next: http://localhost:57262/tests/dromaeo/cssquery-jquery.html) 14:38:11 INFO - PROCESS | 2294 | RSS: Main: 368525312 14:38:11 INFO - PROCESS | 2294 | 14:41:13 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57262/tests/dromaeo/cssquery-mootools.html) 14:41:14 INFO - PROCESS | 2294 | RSS: Main: 363413504 14:41:14 INFO - PROCESS | 2294 | 14:44:16 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57262/tests/dromaeo/cssquery-prototype.html) 14:44:17 INFO - PROCESS | 2294 | RSS: Main: 326348800 14:44:17 INFO - PROCESS | 2294 | 14:47:20 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57262/tests/dromaeo/cssquery-yui.html) 14:47:20 INFO - PROCESS | 2294 | RSS: Main: 327770112 14:47:20 INFO - PROCESS | 2294 | 14:50:23 INFO - PROCESS | 2294 | Cycle 1(1): loaded http://localhost:57262/tests/dromaeo/cssquery-yui.html (next: http://localhost:57262/tests/dromaeo/cssquery-dojo.html) 14:50:23 INFO - PROCESS | 2294 | RSS: Main: 398409728 14:50:23 INFO - PROCESS | 2294 | 14:50:23 INFO - PROCESS | 2294 | __start_tp_report 14:50:23 INFO - PROCESS | 2294 | _x_x_mozilla_page_load 14:50:23 INFO - PROCESS | 2294 | _x_x_mozilla_page_load_details 14:50:23 INFO - PROCESS | 2294 | |i|pagename|runs| 14:50:23 INFO - PROCESS | 2294 | |0;dojo.html;3252.6584708605596;3346.882859099932;3382.526446297518;3388.796672199667;3417.555717756691;5513.9448605514035;5547.722613869306;5564.415736347681;5584.91622625661;5586.497215250632;5156.974215128919;5549.38956714761;5639.2668953036155;5642.915356269659;5654.293213348332;5092.9236061459105;5111.05445492585;5155.355580552431;5170.819021334254;5182;5132.640715149941;5194.974025129869;5207.6875387476875;5208.817691380802;5210.635255532115;5130.820421285256;5152.433232344439;5162.993216322819;5174.586033117342;5177.456367081459;5141.4087379951225;5173.611979101583;5188.948110518884;5207.921881171786;5238.371395432534;5066.974665126688;5107.259447380146;5126.897462050775;5127.077126117298;5184.6629969052265;5523.613347065707;5552.555795536387;5595.692236926968;5600.52395546379;5644.294463192101;6255.437010669061;6261.217347831521;6269.059641053833;6277.403646653561;6283.183186185813;6053.200208628243;6101.005087479836;6261.279952805395;6338.144350512668;6342.270638876496;5730.713464326767;5745.482906538432;5753.59724819267;5774.82675519735;5807.593468457252;6268.717907694165;6340.809775706736;6352.205974253218;6363.427291543783;6373.267074286517;5912.526997840162;6021.969890150521;6030.698465076729;6072.174530046131;6189.6286222825875;6174.567780255339;6214.875702485925;6214.906776398358;6219.689015549205;6233.002719564848;6156.8768624627255;6160.691965401802;6164.938350616526;6171.629702217791;6202.34875338092;6064.211652485149;6114.571104522756;6122.938770612237;6137.162486020116;6220.657863817443;4947.084789313988;4951.257311403318;4982.576480999156;4992.90014199714;4997.85006449807;4395.582419670126;4398.208322501981;4420.027593929331;4421.137878113801;4421.226285400042;5527.281453411111;5642.802501912413;5658.122990936329;5680.5455563554815;5716.599838011382;6163.952128138228;6164.075388691643;6270.905936410957;6276.027215781562;6284.780032698834;5592.7762889484775;5604.915926261109;5605.663660180402;5609.523190528769;5633.126865335879;5274.393444753902;5339.973300133553;5345.572354211654;5346.957343318093;5353.839384818461;6235.407636274546;6238.095476155932;6256.280527739277;6319.747210111544;6320.399562041599;6267.561270711007;6270.404311590391;6289.308176100534;6292.559520833498;6297.4017468340435;6304.747810087545;6319.6208227506495;6326.2092238470195;6328.367163283819;6341.334159913143;5960.612560183574;6118.162361256178;6129.019356902874;6168.0439531872635;6174.66039367839;6140.534923917628;6229.470495007975;6234.220722409699;6240.906386404298;6253.37466253371;6042.84892877664;6046.516278697605;6047.5464340173785;6049.612804847691;6129.84675383119;5924.851878702894;5962.910556341745;5981.431763982415;6008.188894499362;6055.848603784941;5859.94140058594;5870.090136028923;5879.20630714848;5905.911411328748;5919.260092488439;5906.468417842415;5981.671008094422;6054.75780968774;6058.969705151446;6128.63228206309;5761.740721667619;5799.217105690678;5858.502027327725;5910.202122713378;5925.881482370414 14:50:23 INFO - PROCESS | 2294 | |1;ext.html;488.97315637161944;572.9083346664532;575.0396837281739;620.4725982914523;629.9748010079597;29640.962566310183;38279.61720382799;38606.61393386065;38649.61350386495;38722.80638596803;41363.37954930678;42179.36730949038;42280.365794513105;42340.153196936044;42348.3647745284;9465.621375144987;9722.513874306256;9733.464659443727;9735.123838854503;9752.21982241419;9467.57395917182;9512.286578506604;9675;9676.564554595025;9692.854607180898;9463.526823658824;9635.710928672152;9686;9725.902740972606;9727.464989425578;9635.518224088803;9691.854622180603;9696.903030969706;9714.562844672007;9717.708468745877;9551.188149007343;9694.563744631438;9696.224302055889;9699.321047526675;9724.416535007851;40391;40823.79588102041;40839.59160408388;40919.795401022806;41012.589874101475;3132.843357832099;3232.0788575256174;3334.249793796401;3367.736546951434;3615.385384484644;27681.446371072467;28276.575851362246;29573.408531829242;29602.407951841058;29724.40551188964;2690.8923643054254;2718.796090293236;2838.5742138679157;2842.4457230839994;2859.8856045758143;414752;487793;490598;493027;494013;66618;67208;67508;67536;67720.98418523726;71215.2878471219;71404.92892606615;71436;71637.28362716307;71654.64172679208;16431.424900128437;16642.50072497827;16645.91677041607;16678.33286668544;16721.331146754237;12699.365031748375;12734.80897786534;12745.553905613258;12754.234745915275;12756.48974041028;19795.11946365093;21094;21096;21098;21131.78868211329;7423.1092268927;8006.959965200137;8781.385303028726;8870.467771933705;8971;736.7545960046006;740.6629983357559;844.7043534762784;890.3722875372847;954.203240294362;25853.612195817077;26387.47225055488;26796.866015669795;27017.459650806875;27019.729802702113;970.4763521271536;996.8816265435948;1016.446036909871;1050.2280823594651;1149.373591392693;13552.390142443614;13717.519886804113;13907.930460347634;13967.022308438312;14039.947003974743;456640;527929;533931;536219;536235;58309.41690583125;58811;58825.70587147037;58845;58880.41119588835;1311.6851955530794;1415.1155527795129;1441.747694153519;1454.7599646058493;1469.955901322962;22214;28018.71981280161;28279.01023464169;28660.85669571639;28686.42627147487;40819.38770918379;46667.299990499494;47154.528454715015;47160.52839471561;47295;363801;365632;366032;366184;366448;27411.86294068517;31761;33087.83456082801;33572.49641255432;33650.3269934595;40404;41285.38071928863;41360.793196033825;41943.580564193966;42231;34077.14807129842;34457.13857153491;34495.310093798485;35058.298834023684;35184.94445166649;33015.66984330126;33281.83359083189;33580;33873.830630846685;33884.3223135531;36482.27035459329;36713;36852.262954741294;38403;38592.8422147336;30113.849430752707;31512.054638360885;31794.84102579472;33081.50377744384;33364.499532507514;23579;23763.524729505658;24210.03159873585;24249.03003879825;24407.023719051038 14:50:23 INFO - PROCESS | 2294 | |2;jquery.html;286.7897472665353;357.35854141815446;358.18334198028504;359.46619270383474;370.1745108408248;15013.549593512185;15628.2967266473;19075.809241907577;19165.904170479163;20474.488137796543;14811.555653330417;15921.52235432936;19677.90161049193;19749.50626234345;20217.797822021774;5576.749046292906;5845.298564172284;5880.6765627890445;5901.114832775097;5912.704364781765;5781.39295373986;6546.738130474775;6560.343965603425;6562.11411459454;6572.178477690289;6202.9689851550675;6429;6458.773942911999;6490.123833282517;6501.9349806501805;6359.713812878432;6410.903836442457;6411.903821442682;6415.390537898892;6431.807045788634;5425.3489581250105;5480.123180291134;5551.583631227633;5558.388577256498;5598.916016259759;24955;24996.50006999868;25331.113411030525;25502.362440939123;25507.36231594225;2077.356019633903;2380.773826486481;2569.653096831941;2651.68179818423;2827.1760174041333;14568.854311456857;14742.778858317133;14805.111693298437;15059;15081.849181508263;1699.6090899093278;1706.692795296846;1775.1568005197557;1785.5536115971008;1813.2203152644358;88427.55786221092;89814;90630;92486.07513924842;94422.0557794427;9780.657676981271;9897.158741507052;10075.372865768317;10123;10208.897911020944;10577.788444231071;11856.940715296369;12583.433745481292;12937.54718584845;12975.935120324526;7448.590327532038;8094.069182044141;8338.207870252316;8440.366972477088;8535;6931.7227310907;8621.870671939925;8692.826143477096;8729.956350218208;8765.991910930197;4982.402111746614;5479.75341109651;5527.281453411031;6048.123022161762;6117.938820611826;1509.818821741398;1739.5390221591288;1771.5393997560727;1775.2810111904698;1800.8649351298704;858.4977787993969;938.643315540104;943.3113826906397;948.0566835998088;966.6036924860773;14576.708465830836;17620.735688964676;19981.60036799256;20130.69803952942;20316;872.8777971084053;891.4517571693361;966.709987003896;994.5027486256871;1048.8688913885765;9649.951750241344;10744.355338679705;10917.454127293762;10920.890791092146;10930.781384372269;595125;600402;606870;619989;623602;76804.61597691975;99090;101026.49486752666;104560.11239044614;105426;1050.7635781949152;1069.5240617925103;1071.673139692417;1083.7778255457613;1085.218642577343;17036.574085647957;17349.82650173482;17765.911170444582;17895.552611184823;18596;48340.758296208296;48860.2670959943;49050;49447.011059777884;50572.747136264086;64118.39704007437;64295;64494.71010579856;64599.38501537499;64859;7596.278353556403;7842.137364889743;7866.331361834308;7941.6029198541;8183.71357002502;9014.549272536478;11423.200375973602;12260.203086799329;12383.380830958597;12542.93728531388;10065.597376105166;10132.696019119438;10604;10605.840912386166;10657.360558366523;7600.593890130343;8908.688195913111;10734.946325268324;10736.248462607542;10818.945905270422;7348.595827229554;8239.423240373117;10483.580656773644;10527.262731770887;10866.565337386732;10524.105451036441;10732.248742587944;11422.25755325901;11423.371714555778;11439.542418303508;10944.39805810688;10974.725631859268;11003.284786488852;11048.281861678965;11051.336919784839 14:50:23 INFO - PROCESS | 2294 | |3;mootools.html;1432.384074847815;1580.3994482096803;1615.9919200404;1880.9247630094796;2005.7994200579944;7106.005159277699;7756;8632.050474447806;8659.480431174134;8682.696105636312;8432.53621050842;8632.17994290543;8642.178992995656;8648.1784230498;8649.870251946226;2487.291122030217;2512.371907023244;2514.421683012908;2515.1448507507444;2519.067944860395;2452.2030340139436;2545.1346542175656;2547.3886267295807;2548.987255063722;2558.104663367825;2500.949981000379;2519.60946053362;2543.4785868896925;2544.9236522904343;2545.923622291334;2379.4289370551114;2517.005782715826;2532.9746702533016;2538.352720056374;2546.4397832476825;2472.122396549235;2507.2979565721625;2518.244526642;2519.5842685956795;2519.9794083396255;8840.292776577859;8877.73366798997;8893;8904.73285801427;8914.910850891538;5376.704281264529;5503.3395992480755;5515.7242137892945;5548.528375088123;5549.112142057252;3556.484309775068;3643.143861192618;3705.925881482382;3706.610805865372;3714;1043.2123746571367;1064.4252103863905;1066.3228849680509;1073.1468482556386;1076.2950267574752;11896.821547676791;11899.048076153887;11901.28592284457;11912.285262884254;11922.344271065089;4844.273358996179;4873.269009648581;4965.255211718271;4974.452810190876;4985.177445721415;10102.19182465401;10113.59545618167;10116.03897629724;10121.139703125169;10123.544440500194;4628.236341003697;5493.340799104053;5507.862303442446;5511.366192887789;5534.4742249486235;4418.64650827933;4562.475315338712;4872.1473742094995;4981.12830254704;5023.623228257895;3842.51968503937;3929.057026313664;3933.9803300983313;3954.031262340745;3989.601039895987;1193.9940300298617;1263.2799304396406;1313.9211647301192;1321.85459599444;1329.7473480038761;979.647326962293;987.9604815807431;991.1921783746224;992.9106380425796;1025.1644909398817;7802.804929876685;8077.151899050633;8099.716509922125;8107.594620268963;8112.59437028158;1155.3414553704308;1164.959226427071;1170.2861254634665;1212.1454374665839;1215.9392030398444;4901.264810278487;6209.441150296495;6238.532110091761;6239.126522286883;6240.625562466266;10227.488625568692;10509.9474502627;10795.082417994401;10939.726506837234;10958;8990.910090899008;10675.679729608124;10787.56849726018;10803.081738052197;10836.458177091114;8066.314363279187;8411.747647570583;8427.367947403969;8459.873101903477;8467.66129354819;7778.299953004257;7789.688412463438;7850.371970242252;7857.332126769289;7861.606919654108;8560.315174786127;9242.399244049115;9417.246620270498;9477.810443791224;9638.662646807328;9879.369903965766;9960.1533869619;9979.750506237111;10009.799804003733;10013.799724005625;6837.62393068386;7048.013278140962;7048.718051277891;7471.17817040136;7649.158592554926;7278.199398066103;7610;7785.338246249133;8270.462419942685;8317.83364332722;7243.20324764286;7278.944553039885;7387.9261207387235;7407.074115735533;7408.259174082548;7159.212486626571;7178.296736141545;7189.856202876018;7270.200277969313;7305.415566754753;7531.435142364235;7580.128285247267;7633.6946522140715;7642;7652.005239318853;6920.031195632515;6930.168379794457;6982.33667801558;6982.546134501241;6986.16166060076;6984.161900571964;7006.719731210695;7060.7881763547175;7066.293370662893;7074.434045276468 14:50:23 INFO - PROCESS | 2294 | |4;prototype.html;295.99260018499535;311.8803495451329;320.62967272799915;333.416521088096;339.5348372729358;6373.36266373363;6440.130582371378;6510.088587597734;6510.446612037982;6531.902021469682;6435.3886380793865;6441.291457939623;6446.516511261662;6447.871042579157;6461.22465304163;4530.456345238567;4644.373009643688;4672.39258896343;4723.527647235283;4759.690620109699;4740.051989602076;4746.264329029006;4749.192637251658;4751.0972915146185;4896.632752543555;3811.6378944000276;4750.738709370983;4752.714837109785;4757.310190022445;4764.85705428836;4499.437570303712;4588.564086411802;4696.459907110658;4706.247000479907;4708.317293992352;4053.1691003344245;4061.5938406159444;4064.4919385076864;4071.185762847442;4071.531773846016;6109.694515274218;6178.351273116348;6233.501319894398;6254.6247225166635;6264.373562643745;5618.2415373924405;5621;5632.3804381518;5633.943660563383;5634.661920284796;5485.61600687952;6857.725690972356;6868.072810170613;6868.347506986828;6869.622170780605;4282.528921818597;4468.50846406895;4483.282674772054;4484.5067042625615;4487.551244875519;7107;7223.674934627954;7248.311410416002;7249.637518124073;7265.636718164071;4558.97720511402;4559;4559.908801823945;4562.384078149474;4564.018735971782;3769.321522125989;4607.032523170172;4612.3542704021465;4614.815407383734;4622.214223581999;5470.64440811346;5477.643953143033;5481.616286859961;5484.61607687466;5499.037668408013;6620.344913771557;6870.931290687129;6897.275786042494;6905.620190889449;6917.515773895779;1579.0762403993563;1583.9049657020616;1602.3430393538592;1604.1257514654542;1606.2691475378908;956.5360800011989;1010.0858722855827;1012.8581998520214;1031.376017509411;1041.7499800048085;6240.344763799826;6374.330695277022;6518.804435866931;6530.216374035146;6545.14913061299;7196.676149573282;7206.855862882818;7225.8554828903125;7227.5302105364035;7237.3486386225495;6469.967650161813;6476.35236476358;6479.319671434431;6480.4815614750705;6496.6426846522945;3770.2647983643474;3812.9046773830323;3817.8854634361014;3824.732268741217;3830.5211848518934;6008.047384600022;7039.1553013638695;7487.401007919353;7520.849583008309;7524;7307.159676637149;7356.190819009905;7375.262473752582;7381.298776616213;7404.740834070782;5515.669059856422;7246.456515761339;7496.0255166827965;7509.0613673289745;7518.586477743776;7183.640817959185;7188.353048225685;7192.568445893263;7193.71225151009;7197.244289349543;6814.318568143344;7121.03865978107;7138.821529461805;7146.9285307146265;7147.928520714726;7441.851162976818;7573;7578.317951384402;7578.583177925267;7581.203973582695;4735.313379560013;4787.234042553175;4789.856304310876;4792.424909010941;4793.352897358952;7092.5389849659605;7099.467539934629;7102.5028248022145;7110.822229444305;7131.179914309921;6600.339966003361;6861.691223894837;6887.414569761627;6887.793366199022;6896.586204827727;6821.93178068213;6842.8973565397555;6874.931250687429;6892.035115083792;6904.240533541407;6703;6718.059471674067;6718.126643536309;6723.630200339028;6734.4275736560985;6599.769008084694;6850.246472888078;6854.588724676535;6863.519553631198;6885.827854303682;5506.476884695948;6505.21937367519;6545.410913017851;6713.858644030429;6729.865402691821 14:50:23 INFO - PROCESS | 2294 | |5;yui.html;676.4284179868012;738.1990540263813;742.9257074292568;745.5675708089306;756.4666909828574;14145.858541414584;14278.2860856957;14284.642883927907;14316.71366572668;14343.139411635297;13910.026298159133;15680.607984800386;16123.919380403111;16162.11108389038;16185.83814161858;742.0983505041373;747.9401647868185;912.3248795891043;922.9077092290785;930.9813803723921;776.5884081436848;905.443152461235;937.6624415210518;938.249400479617;938.4979036215626;970.5535453691275;970.7670159161786;972.2756546372962;972.5429048347265;973.026973026973;771.7530390275115;786.2805532937356;964.0744884910495;964.6141543382633;966.0725703324816;577.8266520044012;579.7101449275362;588.9322727886305;720.531654424623;724.1889084225649;15364.539063828102;15398.307076181598;15411.537653870402;15422.46021389246;15433.228338583138;12308.876911230862;12357.443915023845;12416.689582760413;12581.056420768387;12882.291473968928;9487;9539.570719317648;9608.66369677058;9906.702798916043;10298.742531436774;3454.896353109411;3471.8264086795557;3483.2336885885065;3488.773229740084;3528.558930133733;81754.91921292624;85711.14288857156;85909.57045214796;86145;86419.13580864173;9212;9249.537523123952;9339.90660093404;9575.186109180797;10061.748456288506;20989.160433582485;21201.363959081253;21253.13082733842;21268.25561105354;21270.787292127192;7425.108986921604;7541.849163016709;7577.810554736066;7842.647080881374;7940.444168908121;4117.1765646870745;4123.505179378434;4143.772092534879;4158.960259935016;4424.756638384855;3593.5508061492314;3656.9943265602174;3659.7621154624862;3660.1215708230366;3661.487391765155;651.6188030002406;662.777969380263;663.5620490476263;673.1484671890063;689.7516893918186;485.761976631448;544.3060098374525;551.7776187067465;592.0586267834165;601.6059481039925;7501.549907005598;7835.686572537149;7926.643301051467;7953.681852725942;8140.389470789714;511.7236692186252;524.281734024389;562.7073921560766;574.6638216643311;597.641415150906;2910.956335654967;3514.6309637488207;3633.818309084535;3678.7608805427562;3759.962400376016;68715.65642171758;81895;81937;88732;94353.5282323584;72685;73791.63104184553;74879.25120748831;75209.6239518799;76809.61595191988;68838.65580672065;68883.11168883072;69740.30259697439;70202.64898675575;70289.94565081528;8377.539235342114;8488.405811593377;8504.319654427754;8550.102239264788;8764.956175219084;47691.76154119207;47774.80562985814;48849.184130538946;49171;49488.25767613561;66892.32769180615;67854.66072669605;70455.64772176107;72569;73297.53404931979;10968.177386696188;10984.670459886216;11166.011826804726;11356.829647555129;11978.10164237703;11778.46996885125;11979.161458697807;12112.878871211175;12418.565350212699;12758.808617870554;11248.100151987985;11345.546178152781;11349.93564162083;11475.254108482923;11595.826062609236;11378;11450.312981221154;11798.469068891749;11817.527298907948;11831.230969986924;11155.274907131336;11304.717382065186;11328.32030078198;11377.886221138015;11433.942830285796;9551.665691700757;9557.569909353957;9644.421334719938;9663.661771837951;10098.848517272394;7488.438367122379;9010.324225682969;9043.954780226057;9139.17216556678;9743.415395076318 14:50:23 INFO - PROCESS | 2294 | __end_tp_report 14:50:23 INFO - PROCESS | 2294 | __start_cc_report 14:50:23 INFO - PROCESS | 2294 | _x_x_mozilla_cycle_collect,1201 14:50:23 INFO - PROCESS | 2294 | __end_cc_report 14:50:23 INFO - PROCESS | 2294 | __startTimestamp1460065823797__endTimestamp 14:50:23 INFO - PROCESS | 2294 | 14:50:23 INFO - PROCESS | 2294 | ------- Summary: start ------- 14:50:23 INFO - PROCESS | 2294 | Number of tests: 6 14:50:23 INFO - PROCESS | 2294 | 14:50:23 INFO - PROCESS | 2294 | [#0] dojo.html Cycles:165 Average:5703.47 Median:5906.47 stddev:639.15 (10.8%) stddev-sans-first:611.51 14:50:23 INFO - PROCESS | 2294 | Values: 3252.7 3346.9 3382.5 3388.8 3417.6 5513.9 5547.7 5564.4 5584.9 5586.5 5157.0 5549.4 5639.3 5642.9 5654.3 5092.9 5111.1 5155.4 5170.8 5182.0 5132.6 5195.0 5207.7 5208.8 5210.6 5130.8 5152.4 5163.0 5174.6 5177.5 5141.4 5173.6 5188.9 5207.9 5238.4 5067.0 5107.3 5126.9 5127.1 5184.7 5523.6 5552.6 5595.7 5600.5 5644.3 6255.4 6261.2 6269.1 6277.4 6283.2 6053.2 6101.0 6261.3 6338.1 6342.3 5730.7 5745.5 5753.6 5774.8 5807.6 6268.7 6340.8 6352.2 6363.4 6373.3 5912.5 6022.0 6030.7 6072.2 6189.6 6174.6 6214.9 6214.9 6219.7 6233.0 6156.9 6160.7 6164.9 6171.6 6202.3 6064.2 6114.6 6122.9 6137.2 6220.7 4947.1 4951.3 4982.6 4992.9 4997.9 4395.6 4398.2 4420.0 4421.1 4421.2 5527.3 5642.8 5658.1 5680.5 5716.6 6164.0 6164.1 6270.9 6276.0 6284.8 5592.8 5604.9 5605.7 5609.5 5633.1 5274.4 5340.0 5345.6 5347.0 5353.8 6235.4 6238.1 6256.3 6319.7 6320.4 6267.6 6270.4 6289.3 6292.6 6297.4 6304.7 6319.6 6326.2 6328.4 6341.3 5960.6 6118.2 6129.0 6168.0 6174.7 6140.5 6229.5 6234.2 6240.9 6253.4 6042.8 6046.5 6047.5 6049.6 6129.8 5924.9 5962.9 5981.4 6008.2 6055.8 5859.9 5870.1 5879.2 5905.9 5919.3 5906.5 5981.7 6054.8 6059.0 6128.6 5761.7 5799.2 5858.5 5910.2 5925.9 14:50:23 INFO - PROCESS | 2294 | 14:50:23 INFO - PROCESS | 2294 | [#1] ext.html Cycles:180 Average:60314.38 Median:38628.11 stddev:121872.52 (315.5%) stddev-sans-first:122131.63 14:50:23 INFO - PROCESS | 2294 | Values: 489.0 572.9 575.0 620.5 630.0 29641.0 38279.6 38606.6 38649.6 38722.8 41363.4 42179.4 42280.4 42340.2 42348.4 9465.6 9722.5 9733.5 9735.1 9752.2 9467.6 9512.3 9675.0 9676.6 9692.9 9463.5 9635.7 9686.0 9725.9 9727.5 9635.5 9691.9 9696.9 9714.6 9717.7 9551.2 9694.6 9696.2 9699.3 9724.4 40391.0 40823.8 40839.6 40919.8 41012.6 3132.8 3232.1 3334.2 3367.7 3615.4 27681.4 28276.6 29573.4 29602.4 29724.4 2690.9 2718.8 2838.6 2842.4 2859.9 414752.0 487793.0 490598.0 493027.0 494013.0 66618.0 67208.0 67508.0 67536.0 67721.0 71215.3 71404.9 71436.0 71637.3 71654.6 16431.4 16642.5 16645.9 16678.3 16721.3 12699.4 12734.8 12745.6 12754.2 12756.5 19795.1 21094.0 21096.0 21098.0 21131.8 7423.1 8007.0 8781.4 8870.5 8971.0 736.8 740.7 844.7 890.4 954.2 25853.6 26387.5 26796.9 27017.5 27019.7 970.5 996.9 1016.4 1050.2 1149.4 13552.4 13717.5 13907.9 13967.0 14039.9 456640.0 527929.0 533931.0 536219.0 536235.0 58309.4 58811.0 58825.7 58845.0 58880.4 1311.7 1415.1 1441.7 1454.8 1470.0 22214.0 28018.7 28279.0 28660.9 28686.4 40819.4 46667.3 47154.5 47160.5 47295.0 363801.0 365632.0 366032.0 366184.0 366448.0 27411.9 31761.0 33087.8 33572.5 33650.3 40404.0 41285.4 41360.8 41943.6 42231.0 34077.1 34457.1 34495.3 35058.3 35184.9 33015.7 33281.8 33580.0 33873.8 33884.3 36482.3 36713.0 36852.3 38403.0 38592.8 30113.8 31512.1 31794.8 33081.5 33364.5 23579.0 23763.5 24210.0 24249.0 24407.0 14:50:23 INFO - PROCESS | 2294 | 14:50:23 INFO - PROCESS | 2294 | [#2] jquery.html Cycles:180 Average:33050.13 Median:1556.98 stddev:100287.61 (6441.2%) stddev-sans-first:100538.77 14:50:23 INFO - PROCESS | 2294 | Values: 286.8 357.4 358.2 359.5 370.2 15013.5 15628.3 19075.8 19165.9 20474.5 14811.6 15921.5 19677.9 19749.5 20217.8 5576.7 5845.3 5880.7 5901.1 5912.7 5781.4 6546.7 6560.3 6562.1 6572.2 6203.0 6429.0 6458.8 6490.1 6501.9 6359.7 6410.9 6411.9 6415.4 6431.8 5425.3 5480.1 5551.6 5558.4 5598.9 24955.0 24996.5 25331.1 25502.4 25507.4 2077.4 2380.8 2569.7 2651.7 2827.2 14568.9 14742.8 14805.1 15059.0 15081.8 1699.6 1706.7 1775.2 1785.6 1813.2 88427.6 89814.0 90630.0 92486.1 94422.1 9780.7 9897.2 10075.4 10123.0 10208.9 10577.8 11856.9 12583.4 12937.5 12975.9 7448.6 8094.1 8338.2 8440.4 8535.0 6931.7 8621.9 8692.8 8730.0 8766.0 4982.4 5479.8 5527.3 6048.1 6117.9 1509.8 1739.5 1771.5 1775.3 1800.9 858.5 938.6 943.3 948.1 966.6 14576.7 17620.7 19981.6 20130.7 20316.0 872.9 891.5 966.7 994.5 1048.9 9650.0 10744.4 10917.5 10920.9 10930.8 595125.0 600402.0 606870.0 619989.0 623602.0 76804.6 99090.0 101026.5 104560.1 105426.0 1050.8 1069.5 1071.7 1083.8 1085.2 17036.6 17349.8 17765.9 17895.6 18596.0 48340.8 48860.3 49050.0 49447.0 50572.7 64118.4 64295.0 64494.7 64599.4 64859.0 7596.3 7842.1 7866.3 7941.6 8183.7 9014.5 11423.2 12260.2 12383.4 12542.9 10065.6 10132.7 10604.0 10605.8 10657.4 7600.6 8908.7 10734.9 10736.2 10818.9 7348.6 8239.4 10483.6 10527.3 10866.6 10524.1 10732.2 11422.3 11423.4 11439.5 10944.4 10974.7 11003.3 11048.3 11051.3 14:50:23 INFO - PROCESS | 2294 | 14:50:23 INFO - PROCESS | 2294 | [#3] mootools.html Cycles:180 Average:6010.94 Median:5498.34 stddev:3146.55 (57.2%) stddev-sans-first:3136.56 14:50:23 INFO - PROCESS | 2294 | Values: 1432.4 1580.4 1616.0 1880.9 2005.8 7106.0 7756.0 8632.1 8659.5 8682.7 8432.5 8632.2 8642.2 8648.2 8649.9 2487.3 2512.4 2514.4 2515.1 2519.1 2452.2 2545.1 2547.4 2549.0 2558.1 2500.9 2519.6 2543.5 2544.9 2545.9 2379.4 2517.0 2533.0 2538.4 2546.4 2472.1 2507.3 2518.2 2519.6 2520.0 8840.3 8877.7 8893.0 8904.7 8914.9 5376.7 5503.3 5515.7 5548.5 5549.1 3556.5 3643.1 3705.9 3706.6 3714.0 1043.2 1064.4 1066.3 1073.1 1076.3 11896.8 11899.0 11901.3 11912.3 11922.3 4844.3 4873.3 4965.3 4974.5 4985.2 10102.2 10113.6 10116.0 10121.1 10123.5 4628.2 5493.3 5507.9 5511.4 5534.5 4418.6 4562.5 4872.1 4981.1 5023.6 3842.5 3929.1 3934.0 3954.0 3989.6 1194.0 1263.3 1313.9 1321.9 1329.7 979.6 988.0 991.2 992.9 1025.2 7802.8 8077.2 8099.7 8107.6 8112.6 1155.3 1165.0 1170.3 1212.1 1215.9 4901.3 6209.4 6238.5 6239.1 6240.6 10227.5 10509.9 10795.1 10939.7 10958.0 8990.9 10675.7 10787.6 10803.1 10836.5 8066.3 8411.7 8427.4 8459.9 8467.7 7778.3 7789.7 7850.4 7857.3 7861.6 8560.3 9242.4 9417.2 9477.8 9638.7 9879.4 9960.2 9979.8 10009.8 10013.8 6837.6 7048.0 7048.7 7471.2 7649.2 7278.2 7610.0 7785.3 8270.5 8317.8 7243.2 7278.9 7387.9 7407.1 7408.3 7159.2 7178.3 7189.9 7270.2 7305.4 7531.4 7580.1 7633.7 7642.0 7652.0 6920.0 6930.2 6982.3 6982.5 6986.2 6984.2 7006.7 7060.8 7066.3 7074.4 14:50:23 INFO - PROCESS | 2294 | 14:50:23 INFO - PROCESS | 2294 | [#4] prototype.html Cycles:180 Average:5633.18 Median:6454.55 stddev:1801.49 (27.9%) stddev-sans-first:1761.45 14:50:23 INFO - PROCESS | 2294 | Values: 296.0 311.9 320.6 333.4 339.5 6373.4 6440.1 6510.1 6510.4 6531.9 6435.4 6441.3 6446.5 6447.9 6461.2 4530.5 4644.4 4672.4 4723.5 4759.7 4740.1 4746.3 4749.2 4751.1 4896.6 3811.6 4750.7 4752.7 4757.3 4764.9 4499.4 4588.6 4696.5 4706.2 4708.3 4053.2 4061.6 4064.5 4071.2 4071.5 6109.7 6178.4 6233.5 6254.6 6264.4 5618.2 5621.0 5632.4 5633.9 5634.7 5485.6 6857.7 6868.1 6868.3 6869.6 4282.5 4468.5 4483.3 4484.5 4487.6 7107.0 7223.7 7248.3 7249.6 7265.6 4559.0 4559.0 4559.9 4562.4 4564.0 3769.3 4607.0 4612.4 4614.8 4622.2 5470.6 5477.6 5481.6 5484.6 5499.0 6620.3 6870.9 6897.3 6905.6 6917.5 1579.1 1583.9 1602.3 1604.1 1606.3 956.5 1010.1 1012.9 1031.4 1041.7 6240.3 6374.3 6518.8 6530.2 6545.1 7196.7 7206.9 7225.9 7227.5 7237.3 6470.0 6476.4 6479.3 6480.5 6496.6 3770.3 3812.9 3817.9 3824.7 3830.5 6008.0 7039.2 7487.4 7520.8 7524.0 7307.2 7356.2 7375.3 7381.3 7404.7 5515.7 7246.5 7496.0 7509.1 7518.6 7183.6 7188.4 7192.6 7193.7 7197.2 6814.3 7121.0 7138.8 7146.9 7147.9 7441.9 7573.0 7578.3 7578.6 7581.2 4735.3 4787.2 4789.9 4792.4 4793.4 7092.5 7099.5 7102.5 7110.8 7131.2 6600.3 6861.7 6887.4 6887.8 6896.6 6821.9 6842.9 6874.9 6892.0 6904.2 6703.0 6718.1 6718.1 6723.6 6734.4 6599.8 6850.2 6854.6 6863.5 6885.8 5506.5 6505.2 6545.4 6713.9 6729.9 14:50:23 INFO - PROCESS | 2294 | 14:50:23 INFO - PROCESS | 2294 | [#5] yui.html Cycles:180 Average:18104.72 Median:626.61 stddev:25202.79 (4022.1%) stddev-sans-first:25239.51 14:50:23 INFO - PROCESS | 2294 | Values: 676.4 738.2 742.9 745.6 756.5 14145.9 14278.3 14284.6 14316.7 14343.1 13910.0 15680.6 16123.9 16162.1 16185.8 742.1 747.9 912.3 922.9 931.0 776.6 905.4 937.7 938.2 938.5 970.6 970.8 972.3 972.5 973.0 771.8 786.3 964.1 964.6 966.1 577.8 579.7 588.9 720.5 724.2 15364.5 15398.3 15411.5 15422.5 15433.2 12308.9 12357.4 12416.7 12581.1 12882.3 9487.0 9539.6 9608.7 9906.7 10298.7 3454.9 3471.8 3483.2 3488.8 3528.6 81754.9 85711.1 85909.6 86145.0 86419.1 9212.0 9249.5 9339.9 9575.2 10061.7 20989.2 21201.4 21253.1 21268.3 21270.8 7425.1 7541.8 7577.8 7842.6 7940.4 4117.2 4123.5 4143.8 4159.0 4424.8 3593.6 3657.0 3659.8 3660.1 3661.5 651.6 662.8 663.6 673.1 689.8 485.8 544.3 551.8 592.1 601.6 7501.5 7835.7 7926.6 7953.7 8140.4 511.7 524.3 562.7 574.7 597.6 2911.0 3514.6 3633.8 3678.8 3760.0 68715.7 81895.0 81937.0 88732.0 94353.5 72685.0 73791.6 74879.3 75209.6 76809.6 68838.7 68883.1 69740.3 70202.6 70289.9 8377.5 8488.4 8504.3 8550.1 8765.0 47691.8 47774.8 48849.2 49171.0 49488.3 66892.3 67854.7 70455.6 72569.0 73297.5 10968.2 10984.7 11166.0 11356.8 11978.1 11778.5 11979.2 12112.9 12418.6 12758.8 11248.1 11345.5 11349.9 11475.3 11595.8 11378.0 11450.3 11798.5 11817.5 11831.2 11155.3 11304.7 11328.3 11377.9 11433.9 9551.7 9557.6 9644.4 9663.7 10098.8 7488.4 9010.3 9044.0 9139.2 9743.4 14:50:23 INFO - PROCESS | 2294 | -------- Summary: end -------- 14:50:23 INFO - PROCESS | 2294 | 14:50:24 INFO - TEST-INFO | 2294: exit 0 14:50:24 INFO - TEST-OK | dromaeo_css | took 1113510ms 14:50:24 INFO - TEST-START | kraken 14:50:24 INFO - operating with platform_type : mac_ 14:50:24 INFO - Initialising browser for kraken test... 14:50:24 INFO - TEST-INFO | started process 2298 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/tmpSnFM9L/profile http://localhost:57262/getInfo.html) 14:50:26 INFO - PROCESS | 2298 | __metrics Screen width/height:1600/1200 14:50:26 INFO - PROCESS | 2298 | colorDepth:24 14:50:26 INFO - PROCESS | 2298 | Browser inner width/height: 1024/689 14:50:26 INFO - PROCESS | 2298 | __metrics 14:50:26 INFO - PROCESS | 2298 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:50:26 ERROR - PROCESS | 2298 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:50:27 INFO - TEST-INFO | 2298: exit 0 14:50:27 INFO - Browser initialized. 14:50:27 INFO - Running cycle 1/1 for kraken test... 14:50:27 INFO - TEST-INFO | started process 2300 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/T/tmpSnFM9L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:50:39 INFO - PROCESS | 2300 | RSS: Main: 236883968 14:50:39 INFO - PROCESS | 2300 | 14:50:41 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-beat-detection) 14:50:42 INFO - PROCESS | 2300 | RSS: Main: 283062272 14:50:42 INFO - PROCESS | 2300 | 14:50:45 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-dft) 14:50:45 INFO - PROCESS | 2300 | RSS: Main: 293736448 14:50:45 INFO - PROCESS | 2300 | 14:50:48 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-fft) 14:50:49 INFO - PROCESS | 2300 | RSS: Main: 278937600 14:50:49 INFO - PROCESS | 2300 | 14:50:51 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57262/tests/kraken/driver.html?testName=audio-oscillator) 14:50:52 INFO - PROCESS | 2300 | RSS: Main: 281645056 14:50:52 INFO - PROCESS | 2300 | 14:50:54 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:50:54 INFO - PROCESS | 2300 | RSS: Main: 282701824 14:50:54 INFO - PROCESS | 2300 | 14:50:57 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57262/tests/kraken/driver.html?testName=imaging-darkroom) 14:50:58 INFO - PROCESS | 2300 | RSS: Main: 282734592 14:50:58 INFO - PROCESS | 2300 | 14:51:00 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57262/tests/kraken/driver.html?testName=imaging-desaturate) 14:51:01 INFO - PROCESS | 2300 | RSS: Main: 283058176 14:51:01 INFO - PROCESS | 2300 | 14:51:04 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57262/tests/kraken/driver.html?testName=json-parse-financial) 14:51:04 INFO - PROCESS | 2300 | RSS: Main: 283209728 14:51:04 INFO - PROCESS | 2300 | 14:51:06 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:51:07 INFO - PROCESS | 2300 | RSS: Main: 317054976 14:51:07 INFO - PROCESS | 2300 | 14:51:08 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:51:09 INFO - PROCESS | 2300 | RSS: Main: 316751872 14:51:09 INFO - PROCESS | 2300 | 14:51:11 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:51:12 INFO - PROCESS | 2300 | RSS: Main: 323641344 14:51:12 INFO - PROCESS | 2300 | 14:51:14 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:51:15 INFO - PROCESS | 2300 | RSS: Main: 325050368 14:51:15 INFO - PROCESS | 2300 | 14:51:17 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:51:18 INFO - PROCESS | 2300 | RSS: Main: 325271552 14:51:18 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | Cycle 1(1): loaded http://localhost:57262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57262/tests/kraken/driver.html?testName=ai-astar) 14:51:20 INFO - PROCESS | 2300 | RSS: Main: 387747840 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | __start_tp_report 14:51:20 INFO - PROCESS | 2300 | _x_x_mozilla_page_load 14:51:20 INFO - PROCESS | 2300 | _x_x_mozilla_page_load_details 14:51:20 INFO - PROCESS | 2300 | |i|pagename|runs| 14:51:20 INFO - PROCESS | 2300 | |0;ai-astar;111;111;109;110;112;112;117;111;111;112 14:51:20 INFO - PROCESS | 2300 | |1;audio-beat-detection;162;127;144;161;120;143;141;120;167;120 14:51:20 INFO - PROCESS | 2300 | |2;audio-dft;187;187;186;187;188;185;178;188;183;183 14:51:20 INFO - PROCESS | 2300 | |3;audio-fft;77;78;81;79;55;57;58;79;78;55 14:51:20 INFO - PROCESS | 2300 | |4;audio-oscillator;93;94;127;78;84;81;119;80;80;120 14:51:20 INFO - PROCESS | 2300 | |5;imaging-gaussian-blur;89;130;90;82;90;87;90;88;90;87 14:51:20 INFO - PROCESS | 2300 | |6;imaging-darkroom;95;95;94;96;94;95;95;95;95;94 14:51:20 INFO - PROCESS | 2300 | |7;imaging-desaturate;86;85;85;85;85;84;85;86;86;86 14:51:20 INFO - PROCESS | 2300 | |8;json-parse-financial;56;55;54;53;53;54;53;64;55;52 14:51:20 INFO - PROCESS | 2300 | |9;json-stringify-tinderbox;66;68;78;78;69;71;69;70;69;70 14:51:20 INFO - PROCESS | 2300 | |10;stanford-crypto-aes;84;83;82;81;86;81;83;82;79;83 14:51:20 INFO - PROCESS | 2300 | |11;stanford-crypto-ccm;120;147;124;107;133;122;122;123;121;131 14:51:20 INFO - PROCESS | 2300 | |12;stanford-crypto-pbkdf2;140;127;140;141;141;140;139;140;138;137 14:51:20 INFO - PROCESS | 2300 | |13;stanford-crypto-sha256-iterative;68;68;65;67;68;68;67;67;67;67 14:51:20 INFO - PROCESS | 2300 | __end_tp_report 14:51:20 INFO - PROCESS | 2300 | __start_cc_report 14:51:20 INFO - PROCESS | 2300 | _x_x_mozilla_cycle_collect,1278 14:51:20 INFO - PROCESS | 2300 | __end_cc_report 14:51:20 INFO - PROCESS | 2300 | __startTimestamp1460065880403__endTimestamp 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | ------- Summary: start ------- 14:51:20 INFO - PROCESS | 2300 | Number of tests: 14 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#0] ai-astar Cycles:10 Average:111.60 Median:111.50 stddev:2.12 (1.9%) stddev-sans-first:2.24 14:51:20 INFO - PROCESS | 2300 | Values: 111.0 111.0 109.0 110.0 112.0 112.0 117.0 111.0 111.0 112.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:143.50 stddev:18.40 (12.8%) stddev-sans-first:17.79 14:51:20 INFO - PROCESS | 2300 | Values: 162.0 127.0 144.0 161.0 120.0 143.0 141.0 120.0 167.0 120.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#2] audio-dft Cycles:10 Average:185.20 Median:187.00 stddev:3.12 (1.7%) stddev-sans-first:3.24 14:51:20 INFO - PROCESS | 2300 | Values: 187.0 187.0 186.0 187.0 188.0 185.0 178.0 188.0 183.0 183.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#3] audio-fft Cycles:10 Average:69.70 Median:78.00 stddev:11.65 (14.9%) stddev-sans-first:12.06 14:51:20 INFO - PROCESS | 2300 | Values: 77.0 78.0 81.0 79.0 55.0 57.0 58.0 79.0 78.0 55.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:80.50 stddev:19.10 (23.7%) stddev-sans-first:20.23 14:51:20 INFO - PROCESS | 2300 | Values: 93.0 94.0 127.0 78.0 84.0 81.0 119.0 80.0 80.0 120.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#5] imaging-gaussian-blur Cycles:10 Average:92.30 Median:89.50 stddev:13.47 (15.1%) stddev-sans-first:14.24 14:51:20 INFO - PROCESS | 2300 | Values: 89.0 130.0 90.0 82.0 90.0 87.0 90.0 88.0 90.0 87.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 14:51:20 INFO - PROCESS | 2300 | Values: 95.0 95.0 94.0 96.0 94.0 95.0 95.0 95.0 95.0 94.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:0.67 (0.8%) stddev-sans-first:0.67 14:51:20 INFO - PROCESS | 2300 | Values: 86.0 85.0 85.0 85.0 85.0 84.0 85.0 86.0 86.0 86.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:54.50 stddev:3.41 (6.3%) stddev-sans-first:3.60 14:51:20 INFO - PROCESS | 2300 | Values: 56.0 55.0 54.0 53.0 53.0 54.0 53.0 64.0 55.0 52.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.00 stddev:4.02 (5.7%) stddev-sans-first:3.87 14:51:20 INFO - PROCESS | 2300 | Values: 66.0 68.0 78.0 78.0 69.0 71.0 69.0 70.0 69.0 70.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:83.00 stddev:1.90 (2.3%) stddev-sans-first:1.92 14:51:20 INFO - PROCESS | 2300 | Values: 84.0 83.0 82.0 81.0 86.0 81.0 83.0 82.0 79.0 83.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:123.50 stddev:10.39 (8.4%) stddev-sans-first:10.86 14:51:20 INFO - PROCESS | 2300 | Values: 120.0 147.0 124.0 107.0 133.0 122.0 122.0 123.0 121.0 131.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.30 Median:140.00 stddev:4.16 (3.0%) stddev-sans-first:4.37 14:51:20 INFO - PROCESS | 2300 | Values: 140.0 127.0 140.0 141.0 141.0 140.0 139.0 140.0 138.0 137.0 14:51:20 INFO - PROCESS | 2300 | 14:51:20 INFO - PROCESS | 2300 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:0.92 (1.4%) stddev-sans-first:0.93 14:51:20 INFO - PROCESS | 2300 | Values: 68.0 68.0 65.0 67.0 68.0 68.0 67.0 67.0 67.0 67.0 14:51:20 INFO - PROCESS | 2300 | -------- Summary: end -------- 14:51:20 INFO - PROCESS | 2300 | 14:51:21 INFO - TEST-INFO | 2300: exit 0 14:51:21 INFO - TEST-OK | kraken | took 56852ms 14:51:21 INFO - SUITE-END | took 1170s 14:51:21 INFO - Completed test suite (00:19:30) 14:51:21 INFO - javascript benchmark 14:51:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3252.6584708605596, 3346.882859099932, 3382.526446297518, 3388.796672199667, 3417.555717756691, 5513.9448605514035, 5547.722613869306, 5564.415736347681, 5584.91622625661, 5586.497215250632, 5156.974215128919, 5549.38956714761, 5639.2668953036155, 5642.915356269659, 5654.293213348332, 5092.9236061459105, 5111.05445492585, 5155.355580552431, 5170.819021334254, 5182.0, 5132.640715149941, 5194.974025129869, 5207.6875387476875, 5208.817691380802, 5210.635255532115, 5130.820421285256, 5152.433232344439, 5162.993216322819, 5174.586033117342, 5177.456367081459, 5141.4087379951225, 5173.611979101583, 5188.948110518884, 5207.921881171786, 5238.371395432534, 5066.974665126688, 5107.259447380146, 5126.897462050775, 5127.077126117298, 5184.6629969052265, 5523.613347065707, 5552.555795536387, 5595.692236926968, 5600.52395546379, 5644.294463192101, 6255.437010669061, 6261.217347831521, 6269.059641053833, 6277.403646653561, 6283.183186185813, 6053.200208628243, 6101.005087479836, 6261.279952805395, 6338.144350512668, 6342.270638876496, 5730.713464326767, 5745.482906538432, 5753.59724819267, 5774.82675519735, 5807.593468457252, 6268.717907694165, 6340.809775706736, 6352.205974253218, 6363.427291543783, 6373.267074286517, 5912.526997840162, 6021.969890150521, 6030.698465076729, 6072.174530046131, 6189.6286222825875, 6174.567780255339, 6214.875702485925, 6214.906776398358, 6219.689015549205, 6233.002719564848, 6156.8768624627255, 6160.691965401802, 6164.938350616526, 6171.629702217791, 6202.34875338092, 6064.211652485149, 6114.571104522756, 6122.938770612237, 6137.162486020116, 6220.657863817443, 4947.084789313988, 4951.257311403318, 4982.576480999156, 4992.90014199714, 4997.85006449807, 4395.582419670126, 4398.208322501981, 4420.027593929331, 4421.137878113801, 4421.226285400042, 5527.281453411111, 5642.802501912413, 5658.122990936329, 5680.5455563554815, 5716.599838011382, 6163.952128138228, 6164.075388691643, 6270.905936410957, 6276.027215781562, 6284.780032698834, 5592.7762889484775, 5604.915926261109, 5605.663660180402, 5609.523190528769, 5633.126865335879, 5274.393444753902, 5339.973300133553, 5345.572354211654, 5346.957343318093, 5353.839384818461, 6235.407636274546, 6238.095476155932, 6256.280527739277, 6319.747210111544, 6320.399562041599, 6267.561270711007, 6270.404311590391, 6289.308176100534, 6292.559520833498, 6297.4017468340435, 6304.747810087545, 6319.6208227506495, 6326.2092238470195, 6328.367163283819, 6341.334159913143, 5960.612560183574, 6118.162361256178, 6129.019356902874, 6168.0439531872635, 6174.66039367839, 6140.534923917628, 6229.470495007975, 6234.220722409699, 6240.906386404298, 6253.37466253371, 6042.84892877664, 6046.516278697605, 6047.5464340173785, 6049.612804847691, 6129.84675383119, 5924.851878702894, 5962.910556341745, 5981.431763982415, 6008.188894499362, 6055.848603784941, 5859.94140058594, 5870.090136028923, 5879.20630714848, 5905.911411328748, 5919.260092488439, 5906.468417842415, 5981.671008094422, 6054.75780968774, 6058.969705151446, 6128.63228206309, 5761.740721667619, 5799.217105690678, 5858.502027327725, 5910.202122713378, 5925.881482370414], "value": 5661.803847456657, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.97315637161944, 572.9083346664532, 575.0396837281739, 620.4725982914523, 629.9748010079597, 29640.962566310183, 38279.61720382799, 38606.61393386065, 38649.61350386495, 38722.80638596803, 41363.37954930678, 42179.36730949038, 42280.365794513105, 42340.153196936044, 42348.3647745284, 9465.621375144987, 9722.513874306256, 9733.464659443727, 9735.123838854503, 9752.21982241419, 9467.57395917182, 9512.286578506604, 9675.0, 9676.564554595025, 9692.854607180898, 9463.526823658824, 9635.710928672152, 9686.0, 9725.902740972606, 9727.464989425578, 9635.518224088803, 9691.854622180603, 9696.903030969706, 9714.562844672007, 9717.708468745877, 9551.188149007343, 9694.563744631438, 9696.224302055889, 9699.321047526675, 9724.416535007851, 40391.0, 40823.79588102041, 40839.59160408388, 40919.795401022806, 41012.589874101475, 3132.843357832099, 3232.0788575256174, 3334.249793796401, 3367.736546951434, 3615.385384484644, 27681.446371072467, 28276.575851362246, 29573.408531829242, 29602.407951841058, 29724.40551188964, 2690.8923643054254, 2718.796090293236, 2838.5742138679157, 2842.4457230839994, 2859.8856045758143, 414752.0, 487793.0, 490598.0, 493027.0, 494013.0, 66618.0, 67208.0, 67508.0, 67536.0, 67720.98418523726, 71215.2878471219, 71404.92892606615, 71436.0, 71637.28362716307, 71654.64172679208, 16431.424900128437, 16642.50072497827, 16645.91677041607, 16678.33286668544, 16721.331146754237, 12699.365031748375, 12734.80897786534, 12745.553905613258, 12754.234745915275, 12756.48974041028, 19795.11946365093, 21094.0, 21096.0, 21098.0, 21131.78868211329, 7423.1092268927, 8006.959965200137, 8781.385303028726, 8870.467771933705, 8971.0, 736.7545960046006, 740.6629983357559, 844.7043534762784, 890.3722875372847, 954.203240294362, 25853.612195817077, 26387.47225055488, 26796.866015669795, 27017.459650806875, 27019.729802702113, 970.4763521271536, 996.8816265435948, 1016.446036909871, 1050.2280823594651, 1149.373591392693, 13552.390142443614, 13717.519886804113, 13907.930460347634, 13967.022308438312, 14039.947003974743, 456640.0, 527929.0, 533931.0, 536219.0, 536235.0, 58309.41690583125, 58811.0, 58825.70587147037, 58845.0, 58880.41119588835, 1311.6851955530794, 1415.1155527795129, 1441.747694153519, 1454.7599646058493, 1469.955901322962, 22214.0, 28018.71981280161, 28279.01023464169, 28660.85669571639, 28686.42627147487, 40819.38770918379, 46667.299990499494, 47154.528454715015, 47160.52839471561, 47295.0, 363801.0, 365632.0, 366032.0, 366184.0, 366448.0, 27411.86294068517, 31761.0, 33087.83456082801, 33572.49641255432, 33650.3269934595, 40404.0, 41285.38071928863, 41360.793196033825, 41943.580564193966, 42231.0, 34077.14807129842, 34457.13857153491, 34495.310093798485, 35058.298834023684, 35184.94445166649, 33015.66984330126, 33281.83359083189, 33580.0, 33873.830630846685, 33884.3223135531, 36482.27035459329, 36713.0, 36852.262954741294, 38403.0, 38592.8422147336, 30113.849430752707, 31512.054638360885, 31794.84102579472, 33081.50377744384, 33364.499532507514, 23579.0, 23763.524729505658, 24210.03159873585, 24249.03003879825, 24407.023719051038], "value": 19312.96029741783, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.7897472665353, 357.35854141815446, 358.18334198028504, 359.46619270383474, 370.1745108408248, 15013.549593512185, 15628.2967266473, 19075.809241907577, 19165.904170479163, 20474.488137796543, 14811.555653330417, 15921.52235432936, 19677.90161049193, 19749.50626234345, 20217.797822021774, 5576.749046292906, 5845.298564172284, 5880.6765627890445, 5901.114832775097, 5912.704364781765, 5781.39295373986, 6546.738130474775, 6560.343965603425, 6562.11411459454, 6572.178477690289, 6202.9689851550675, 6429.0, 6458.773942911999, 6490.123833282517, 6501.9349806501805, 6359.713812878432, 6410.903836442457, 6411.903821442682, 6415.390537898892, 6431.807045788634, 5425.3489581250105, 5480.123180291134, 5551.583631227633, 5558.388577256498, 5598.916016259759, 24955.0, 24996.50006999868, 25331.113411030525, 25502.362440939123, 25507.36231594225, 2077.356019633903, 2380.773826486481, 2569.653096831941, 2651.68179818423, 2827.1760174041333, 14568.854311456857, 14742.778858317133, 14805.111693298437, 15059.0, 15081.849181508263, 1699.6090899093278, 1706.692795296846, 1775.1568005197557, 1785.5536115971008, 1813.2203152644358, 88427.55786221092, 89814.0, 90630.0, 92486.07513924842, 94422.0557794427, 9780.657676981271, 9897.158741507052, 10075.372865768317, 10123.0, 10208.897911020944, 10577.788444231071, 11856.940715296369, 12583.433745481292, 12937.54718584845, 12975.935120324526, 7448.590327532038, 8094.069182044141, 8338.207870252316, 8440.366972477088, 8535.0, 6931.7227310907, 8621.870671939925, 8692.826143477096, 8729.956350218208, 8765.991910930197, 4982.402111746614, 5479.75341109651, 5527.281453411031, 6048.123022161762, 6117.938820611826, 1509.818821741398, 1739.5390221591288, 1771.5393997560727, 1775.2810111904698, 1800.8649351298704, 858.4977787993969, 938.643315540104, 943.3113826906397, 948.0566835998088, 966.6036924860773, 14576.708465830836, 17620.735688964676, 19981.60036799256, 20130.69803952942, 20316.0, 872.8777971084053, 891.4517571693361, 966.709987003896, 994.5027486256871, 1048.8688913885765, 9649.951750241344, 10744.355338679705, 10917.454127293762, 10920.890791092146, 10930.781384372269, 595125.0, 600402.0, 606870.0, 619989.0, 623602.0, 76804.61597691975, 99090.0, 101026.49486752666, 104560.11239044614, 105426.0, 1050.7635781949152, 1069.5240617925103, 1071.673139692417, 1083.7778255457613, 1085.218642577343, 17036.574085647957, 17349.82650173482, 17765.911170444582, 17895.552611184823, 18596.0, 48340.758296208296, 48860.2670959943, 49050.0, 49447.011059777884, 50572.747136264086, 64118.39704007437, 64295.0, 64494.71010579856, 64599.38501537499, 64859.0, 7596.278353556403, 7842.137364889743, 7866.331361834308, 7941.6029198541, 8183.71357002502, 9014.549272536478, 11423.200375973602, 12260.203086799329, 12383.380830958597, 12542.93728531388, 10065.597376105166, 10132.696019119438, 10604.0, 10605.840912386166, 10657.360558366523, 7600.593890130343, 8908.688195913111, 10734.946325268324, 10736.248462607542, 10818.945905270422, 7348.595827229554, 8239.423240373117, 10483.580656773644, 10527.262731770887, 10866.565337386732, 10524.105451036441, 10732.248742587944, 11422.25755325901, 11423.371714555778, 11439.542418303508, 10944.39805810688, 10974.725631859268, 11003.284786488852, 11048.281861678965, 11051.336919784839], "value": 9314.564055997356, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.384074847815, 1580.3994482096803, 1615.9919200404, 1880.9247630094796, 2005.7994200579944, 7106.005159277699, 7756.0, 8632.050474447806, 8659.480431174134, 8682.696105636312, 8432.53621050842, 8632.17994290543, 8642.178992995656, 8648.1784230498, 8649.870251946226, 2487.291122030217, 2512.371907023244, 2514.421683012908, 2515.1448507507444, 2519.067944860395, 2452.2030340139436, 2545.1346542175656, 2547.3886267295807, 2548.987255063722, 2558.104663367825, 2500.949981000379, 2519.60946053362, 2543.4785868896925, 2544.9236522904343, 2545.923622291334, 2379.4289370551114, 2517.005782715826, 2532.9746702533016, 2538.352720056374, 2546.4397832476825, 2472.122396549235, 2507.2979565721625, 2518.244526642, 2519.5842685956795, 2519.9794083396255, 8840.292776577859, 8877.73366798997, 8893.0, 8904.73285801427, 8914.910850891538, 5376.704281264529, 5503.3395992480755, 5515.7242137892945, 5548.528375088123, 5549.112142057252, 3556.484309775068, 3643.143861192618, 3705.925881482382, 3706.610805865372, 3714.0, 1043.2123746571367, 1064.4252103863905, 1066.3228849680509, 1073.1468482556386, 1076.2950267574752, 11896.821547676791, 11899.048076153887, 11901.28592284457, 11912.285262884254, 11922.344271065089, 4844.273358996179, 4873.269009648581, 4965.255211718271, 4974.452810190876, 4985.177445721415, 10102.19182465401, 10113.59545618167, 10116.03897629724, 10121.139703125169, 10123.544440500194, 4628.236341003697, 5493.340799104053, 5507.862303442446, 5511.366192887789, 5534.4742249486235, 4418.64650827933, 4562.475315338712, 4872.1473742094995, 4981.12830254704, 5023.623228257895, 3842.51968503937, 3929.057026313664, 3933.9803300983313, 3954.031262340745, 3989.601039895987, 1193.9940300298617, 1263.2799304396406, 1313.9211647301192, 1321.85459599444, 1329.7473480038761, 979.647326962293, 987.9604815807431, 991.1921783746224, 992.9106380425796, 1025.1644909398817, 7802.804929876685, 8077.151899050633, 8099.716509922125, 8107.594620268963, 8112.59437028158, 1155.3414553704308, 1164.959226427071, 1170.2861254634665, 1212.1454374665839, 1215.9392030398444, 4901.264810278487, 6209.441150296495, 6238.532110091761, 6239.126522286883, 6240.625562466266, 10227.488625568692, 10509.9474502627, 10795.082417994401, 10939.726506837234, 10958.0, 8990.910090899008, 10675.679729608124, 10787.56849726018, 10803.081738052197, 10836.458177091114, 8066.314363279187, 8411.747647570583, 8427.367947403969, 8459.873101903477, 8467.66129354819, 7778.299953004257, 7789.688412463438, 7850.371970242252, 7857.332126769289, 7861.606919654108, 8560.315174786127, 9242.399244049115, 9417.246620270498, 9477.810443791224, 9638.662646807328, 9879.369903965766, 9960.1533869619, 9979.750506237111, 10009.799804003733, 10013.799724005625, 6837.62393068386, 7048.013278140962, 7048.718051277891, 7471.17817040136, 7649.158592554926, 7278.199398066103, 7610.0, 7785.338246249133, 8270.462419942685, 8317.83364332722, 7243.20324764286, 7278.944553039885, 7387.9261207387235, 7407.074115735533, 7408.259174082548, 7159.212486626571, 7178.296736141545, 7189.856202876018, 7270.200277969313, 7305.415566754753, 7531.435142364235, 7580.128285247267, 7633.6946522140715, 7642.0, 7652.005239318853, 6920.031195632515, 6930.168379794457, 6982.33667801558, 6982.546134501241, 6986.16166060076, 6984.161900571964, 7006.719731210695, 7060.7881763547175, 7066.293370662893, 7074.434045276468], "value": 4908.731732027264, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.99260018499535, 311.8803495451329, 320.62967272799915, 333.416521088096, 339.5348372729358, 6373.36266373363, 6440.130582371378, 6510.088587597734, 6510.446612037982, 6531.902021469682, 6435.3886380793865, 6441.291457939623, 6446.516511261662, 6447.871042579157, 6461.22465304163, 4530.456345238567, 4644.373009643688, 4672.39258896343, 4723.527647235283, 4759.690620109699, 4740.051989602076, 4746.264329029006, 4749.192637251658, 4751.0972915146185, 4896.632752543555, 3811.6378944000276, 4750.738709370983, 4752.714837109785, 4757.310190022445, 4764.85705428836, 4499.437570303712, 4588.564086411802, 4696.459907110658, 4706.247000479907, 4708.317293992352, 4053.1691003344245, 4061.5938406159444, 4064.4919385076864, 4071.185762847442, 4071.531773846016, 6109.694515274218, 6178.351273116348, 6233.501319894398, 6254.6247225166635, 6264.373562643745, 5618.2415373924405, 5621.0, 5632.3804381518, 5633.943660563383, 5634.661920284796, 5485.61600687952, 6857.725690972356, 6868.072810170613, 6868.347506986828, 6869.622170780605, 4282.528921818597, 4468.50846406895, 4483.282674772054, 4484.5067042625615, 4487.551244875519, 7107.0, 7223.674934627954, 7248.311410416002, 7249.637518124073, 7265.636718164071, 4558.97720511402, 4559.0, 4559.908801823945, 4562.384078149474, 4564.018735971782, 3769.321522125989, 4607.032523170172, 4612.3542704021465, 4614.815407383734, 4622.214223581999, 5470.64440811346, 5477.643953143033, 5481.616286859961, 5484.61607687466, 5499.037668408013, 6620.344913771557, 6870.931290687129, 6897.275786042494, 6905.620190889449, 6917.515773895779, 1579.0762403993563, 1583.9049657020616, 1602.3430393538592, 1604.1257514654542, 1606.2691475378908, 956.5360800011989, 1010.0858722855827, 1012.8581998520214, 1031.376017509411, 1041.7499800048085, 6240.344763799826, 6374.330695277022, 6518.804435866931, 6530.216374035146, 6545.14913061299, 7196.676149573282, 7206.855862882818, 7225.8554828903125, 7227.5302105364035, 7237.3486386225495, 6469.967650161813, 6476.35236476358, 6479.319671434431, 6480.4815614750705, 6496.6426846522945, 3770.2647983643474, 3812.9046773830323, 3817.8854634361014, 3824.732268741217, 3830.5211848518934, 6008.047384600022, 7039.1553013638695, 7487.401007919353, 7520.849583008309, 7524.0, 7307.159676637149, 7356.190819009905, 7375.262473752582, 7381.298776616213, 7404.740834070782, 5515.669059856422, 7246.456515761339, 7496.0255166827965, 7509.0613673289745, 7518.586477743776, 7183.640817959185, 7188.353048225685, 7192.568445893263, 7193.71225151009, 7197.244289349543, 6814.318568143344, 7121.03865978107, 7138.821529461805, 7146.9285307146265, 7147.928520714726, 7441.851162976818, 7573.0, 7578.317951384402, 7578.583177925267, 7581.203973582695, 4735.313379560013, 4787.234042553175, 4789.856304310876, 4792.424909010941, 4793.352897358952, 7092.5389849659605, 7099.467539934629, 7102.5028248022145, 7110.822229444305, 7131.179914309921, 6600.339966003361, 6861.691223894837, 6887.414569761627, 6887.793366199022, 6896.586204827727, 6821.93178068213, 6842.8973565397555, 6874.931250687429, 6892.035115083792, 6904.240533541407, 6703.0, 6718.059471674067, 6718.126643536309, 6723.630200339028, 6734.4275736560985, 6599.769008084694, 6850.246472888078, 6854.588724676535, 6863.519553631198, 6885.827854303682, 5506.476884695948, 6505.21937367519, 6545.410913017851, 6713.858644030429, 6729.865402691821], "value": 5027.51365232155, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.4284179868012, 738.1990540263813, 742.9257074292568, 745.5675708089306, 756.4666909828574, 14145.858541414584, 14278.2860856957, 14284.642883927907, 14316.71366572668, 14343.139411635297, 13910.026298159133, 15680.607984800386, 16123.919380403111, 16162.11108389038, 16185.83814161858, 742.0983505041373, 747.9401647868185, 912.3248795891043, 922.9077092290785, 930.9813803723921, 776.5884081436848, 905.443152461235, 937.6624415210518, 938.249400479617, 938.4979036215626, 970.5535453691275, 970.7670159161786, 972.2756546372962, 972.5429048347265, 973.026973026973, 771.7530390275115, 786.2805532937356, 964.0744884910495, 964.6141543382633, 966.0725703324816, 577.8266520044012, 579.7101449275362, 588.9322727886305, 720.531654424623, 724.1889084225649, 15364.539063828102, 15398.307076181598, 15411.537653870402, 15422.46021389246, 15433.228338583138, 12308.876911230862, 12357.443915023845, 12416.689582760413, 12581.056420768387, 12882.291473968928, 9487.0, 9539.570719317648, 9608.66369677058, 9906.702798916043, 10298.742531436774, 3454.896353109411, 3471.8264086795557, 3483.2336885885065, 3488.773229740084, 3528.558930133733, 81754.91921292624, 85711.14288857156, 85909.57045214796, 86145.0, 86419.13580864173, 9212.0, 9249.537523123952, 9339.90660093404, 9575.186109180797, 10061.748456288506, 20989.160433582485, 21201.363959081253, 21253.13082733842, 21268.25561105354, 21270.787292127192, 7425.108986921604, 7541.849163016709, 7577.810554736066, 7842.647080881374, 7940.444168908121, 4117.1765646870745, 4123.505179378434, 4143.772092534879, 4158.960259935016, 4424.756638384855, 3593.5508061492314, 3656.9943265602174, 3659.7621154624862, 3660.1215708230366, 3661.487391765155, 651.6188030002406, 662.777969380263, 663.5620490476263, 673.1484671890063, 689.7516893918186, 485.761976631448, 544.3060098374525, 551.7776187067465, 592.0586267834165, 601.6059481039925, 7501.549907005598, 7835.686572537149, 7926.643301051467, 7953.681852725942, 8140.389470789714, 511.7236692186252, 524.281734024389, 562.7073921560766, 574.6638216643311, 597.641415150906, 2910.956335654967, 3514.6309637488207, 3633.818309084535, 3678.7608805427562, 3759.962400376016, 68715.65642171758, 81895.0, 81937.0, 88732.0, 94353.5282323584, 72685.0, 73791.63104184553, 74879.25120748831, 75209.6239518799, 76809.61595191988, 68838.65580672065, 68883.11168883072, 69740.30259697439, 70202.64898675575, 70289.94565081528, 8377.539235342114, 8488.405811593377, 8504.319654427754, 8550.102239264788, 8764.956175219084, 47691.76154119207, 47774.80562985814, 48849.184130538946, 49171.0, 49488.25767613561, 66892.32769180615, 67854.66072669605, 70455.64772176107, 72569.0, 73297.53404931979, 10968.177386696188, 10984.670459886216, 11166.011826804726, 11356.829647555129, 11978.10164237703, 11778.46996885125, 11979.161458697807, 12112.878871211175, 12418.565350212699, 12758.808617870554, 11248.100151987985, 11345.546178152781, 11349.93564162083, 11475.254108482923, 11595.826062609236, 11378.0, 11450.312981221154, 11798.469068891749, 11817.527298907948, 11831.230969986924, 11155.274907131336, 11304.717382065186, 11328.32030078198, 11377.886221138015, 11433.942830285796, 9551.665691700757, 9557.569909353957, 9644.421334719938, 9663.661771837951, 10098.848517272394, 7488.438367122379, 9010.324225682969, 9043.954780226057, 9139.17216556678, 9743.415395076318], "value": 6842.599174448279, "unit": "score"}], "name": "dromaeo_css", "value": 7457.457084425651, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 109.0, 110.0, 112.0, 112.0, 117.0, 111.0, 111.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 127.0, 144.0, 161.0, 120.0, 143.0, 141.0, 120.0, 167.0, 120.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 186.0, 187.0, 188.0, 185.0, 178.0, 188.0, 183.0, 183.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 81.0, 79.0, 55.0, 57.0, 58.0, 79.0, 78.0, 55.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 127.0, 78.0, 84.0, 81.0, 119.0, 80.0, 80.0, 120.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 130.0, 90.0, 82.0, 90.0, 87.0, 90.0, 88.0, 90.0, 87.0], "value": 92.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 85.0, 85.0, 84.0, 85.0, 86.0, 86.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 53.0, 53.0, 54.0, 53.0, 64.0, 55.0, 52.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 78.0, 78.0, 69.0, 71.0, 69.0, 70.0, 69.0, 70.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 81.0, 86.0, 81.0, 83.0, 82.0, 79.0, 83.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 147.0, 124.0, 107.0, 133.0, 122.0, 122.0, 123.0, 121.0, 131.0], "value": 125.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 127.0, 140.0, 141.0, 141.0, 140.0, 139.0, 140.0, 138.0, 137.0], "value": 138.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 65.0, 67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1413.5999999999997, "alertThreshold": 2.0}]} 14:51:21 INFO - Return code: 0 14:51:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:51:21 ERROR - # TBPL SUCCESS # 14:51:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:51:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:51:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:51:21 INFO - 2016-04-07 14:51:21.741 screenresolution[2304:23129] starting screenresolution argv=screenresolution get 14:51:21 INFO - 2016-04-07 14:51:21.744 screenresolution[2304:23129] Display 0: 1600x1200x32@60 14:51:21 INFO - 2016-04-07 14:51:21.749 screenresolution[2305:23132] starting screenresolution argv=screenresolution list 14:51:21 INFO - Available Modes on Display 0 14:51:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:51:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:51:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:51:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:51:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:51:21 INFO - Intel Iris: 14:51:21 INFO - Chipset Model: Intel Iris 14:51:21 INFO - Type: GPU 14:51:21 INFO - Bus: Built-In 14:51:21 INFO - VRAM (Dynamic, Max): 1536 MB 14:51:21 INFO - Vendor: Intel (0x8086) 14:51:21 INFO - Device ID: 0x0a2e 14:51:21 INFO - Revision ID: 0x0009 14:51:21 INFO - Displays: 14:51:21 INFO - Display: 14:51:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:51:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:51:21 INFO - Display Serial Number: 1600x1200 60 14:51:21 INFO - Main Display: Yes 14:51:21 INFO - Mirror: Off 14:51:21 INFO - Online: Yes 14:51:21 INFO - Rotation: Supported 14:51:21 INFO - Return code: 0 14:51:21 INFO - Running post-action listener: _resource_record_post_action 14:51:21 INFO - Running post-run listener: _resource_record_post_run 14:51:23 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 102400; Write bytes: 692555264; Read time: 5; Write time: 2221 14:51:23 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393017856; Write bytes: 395868160; Read time: 29927; Write time: 787 14:51:23 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 102400; Write bytes: 295753216; Read time: 5; Write time: 1430 14:51:23 INFO - Running post-run listener: _upload_blobber_files 14:51:23 INFO - Blob upload gear active. 14:51:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:51:23 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 14:51:23 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'] 14:51:23 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 14:51:23 INFO - (blobuploader) - INFO - Open directory for files ... 14:51:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:51:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:51:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:51:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:51:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:51:24 INFO - (blobuploader) - INFO - Done attempting. 14:51:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:51:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:51:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:51:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:51:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:51:25 INFO - (blobuploader) - INFO - Done attempting. 14:51:25 INFO - (blobuploader) - INFO - Iteration through files over. 14:51:25 INFO - Return code: 0 14:51:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:51:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:51:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3b36ff780731db008b7db0029c5df1963c87bd9d4312a846c33a494ea556643f5b9bd3a9c1cecde37616018584218be3e980ad91a1a72b1343e5c089099543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db4955d0b08bb80b54cb26b4d95d5674499ecdf08e84b49e362b13ec9cb46daeddfe0a502251c17bb5b2ba66b0fc2dba0bd9bbd0284ac8597521865b20447c7f"} 14:51:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:51:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:51:25 INFO - Contents: 14:51:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3b36ff780731db008b7db0029c5df1963c87bd9d4312a846c33a494ea556643f5b9bd3a9c1cecde37616018584218be3e980ad91a1a72b1343e5c089099543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db4955d0b08bb80b54cb26b4d95d5674499ecdf08e84b49e362b13ec9cb46daeddfe0a502251c17bb5b2ba66b0fc2dba0bd9bbd0284ac8597521865b20447c7f"} 14:51:25 INFO - Running post-run listener: copy_logs_to_upload_dir 14:51:25 INFO - Copying logs to upload dir... 14:51:25 INFO - mkdir: /builds/slave/test/build/upload/logs 14:51:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.078947 ========= master_lag: 3.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-07 14:51:28.916591) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 14:51:28.921294) ========= 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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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/af3b36ff780731db008b7db0029c5df1963c87bd9d4312a846c33a494ea556643f5b9bd3a9c1cecde37616018584218be3e980ad91a1a72b1343e5c089099543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db4955d0b08bb80b54cb26b4d95d5674499ecdf08e84b49e362b13ec9cb46daeddfe0a502251c17bb5b2ba66b0fc2dba0bd9bbd0284ac8597521865b20447c7f"} build_url:https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/UOaaEEsuQ0-H8Mt9F0Ripw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3b36ff780731db008b7db0029c5df1963c87bd9d4312a846c33a494ea556643f5b9bd3a9c1cecde37616018584218be3e980ad91a1a72b1343e5c089099543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db4955d0b08bb80b54cb26b4d95d5674499ecdf08e84b49e362b13ec9cb46daeddfe0a502251c17bb5b2ba66b0fc2dba0bd9bbd0284ac8597521865b20447c7f"}' ========= master_lag: 0.64 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 14:51:29.572783) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 14:51:29.573098) ========= 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.FUR2TK7Qt0/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.bOPQ3xJ3GS/Listeners TMPDIR=/var/folders/4t/8gw461qn3ds6ckqrrr1_mkzc00000w/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.005234 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 14:51:29.612397) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-07 14:51:29.612717) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-07 14:51:33.517032) ========= ========= Total master_lag: 30.38 =========