builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0112 starttime: 1454762832.87 results: success (0) buildid: 20160206044526 builduid: f0a6fa61c38e4edca5487dadb9c55727 revision: 97c27a348f09 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:12.866629) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:12.866998) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:12.867272) ========= 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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005730 basedir: '/builds/slave/test' ========= master_lag: 0.06 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:12.928890) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:12.929207) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:12.944488) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:12.944768) ========= 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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005290 ========= master_lag: 0.56 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:13.508804) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:13.509146) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:13.512521) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:13.512805) ========= 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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-02-06 04:47:14-- 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% 10.4M=0.001s 2016-02-06 04:47:14 (10.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.158389 ========= master_lag: 0.83 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:14.500668) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:14.500998) ========= 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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.019417 ========= master_lag: 0.34 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:14.856040) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-06 04:47:14.856368) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 97c27a348f09 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 97c27a348f09 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-02-06 04:47:14,929 Setting DEBUG logging. 2016-02-06 04:47:14,929 attempt 1/10 2016-02-06 04:47:14,929 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/97c27a348f09?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-06 04:47:16,216 unpacking tar archive at: fx-team-97c27a348f09/testing/mozharness/ program finished with exit code 0 elapsedTime=1.534593 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-06 04:47:16.412702) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:16.413131) ========= echo 97c27a348f09 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'97c27a348f09'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 97c27a348f09 program finished with exit code 0 elapsedTime=0.005348 script_repo_revision: '97c27a348f09' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:16.471670) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:16.472332) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-06 04:47:16.495451) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-02-06 04:47:16.495733) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:16 INFO - MultiFileLogger online at 20160206 04:47:16 in /builds/slave/test 04:47:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:47:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:47:16 INFO - {'append_to_log': False, 04:47:16 INFO - 'base_work_dir': '/builds/slave/test', 04:47:16 INFO - 'blob_upload_branch': 'Fx-Team', 04:47:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:47:16 INFO - 'branch': 'Fx-Team', 04:47:16 INFO - 'buildbot_json_path': 'buildprops.json', 04:47:16 INFO - 'config_files': ('talos/mac_config.py',), 04:47:16 INFO - 'default_actions': ('clobber', 04:47:16 INFO - 'read-buildbot-config', 04:47:16 INFO - 'download-and-extract', 04:47:16 INFO - 'populate-webroot', 04:47:16 INFO - 'create-virtualenv', 04:47:16 INFO - 'install', 04:47:16 INFO - 'run-tests'), 04:47:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:47:16 INFO - 'download_minidump_stackwalk': True, 04:47:16 INFO - 'download_symbols': 'ondemand', 04:47:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:47:16 INFO - 'tooltool.py': '/tools/tooltool.py', 04:47:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:47:16 INFO - '/tools/misc-python/virtualenv.py')}, 04:47:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:47:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:47:16 INFO - 'installer_path': 'installer.exe', 04:47:16 INFO - 'log_level': 'info', 04:47:16 INFO - 'log_name': 'talos', 04:47:16 INFO - 'log_to_console': True, 04:47:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:47:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:47:16 INFO - 'opt_config_files': (), 04:47:16 INFO - 'pip_index': False, 04:47:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:16 INFO - 'cmd': ('bash', 04:47:16 INFO - '-c', 04:47:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:16 INFO - 'enabled': True, 04:47:16 INFO - 'halt_on_failure': False, 04:47:16 INFO - 'name': 'check_screen_resolution'},), 04:47:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:16 INFO - 'cmd': ('bash', 04:47:16 INFO - '-c', 04:47:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:16 INFO - 'enabled': True, 04:47:16 INFO - 'halt_on_failure': False, 04:47:16 INFO - 'name': 'check_screen_resolution'},), 04:47:16 INFO - 'run_cmd_checks_enabled': True, 04:47:16 INFO - 'sps_profile': False, 04:47:16 INFO - 'sps_profile_interval': 0, 04:47:16 INFO - 'suite': 'dromaeojs', 04:47:16 INFO - 'system_bits': '32', 04:47:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:47:16 INFO - 'title': 't-yosemite-r7-0112', 04:47:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:47:16 INFO - 'use_talos_json': True, 04:47:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:47:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:47:16 INFO - 'work_dir': 'build'} 04:47:16 INFO - ##### 04:47:16 INFO - ##### Running clobber step. 04:47:16 INFO - ##### 04:47:16 INFO - Running pre-action listener: _resource_record_pre_action 04:47:16 INFO - Running main action method: clobber 04:47:16 INFO - rmtree: /builds/slave/test/build 04:47:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:47:17 INFO - Running post-action listener: _resource_record_post_action 04:47:17 INFO - ##### 04:47:17 INFO - ##### Running read-buildbot-config step. 04:47:17 INFO - ##### 04:47:17 INFO - Running pre-action listener: _resource_record_pre_action 04:47:17 INFO - Running main action method: read_buildbot_config 04:47:17 INFO - Using buildbot properties: 04:47:17 INFO - { 04:47:17 INFO - "project": "", 04:47:17 INFO - "product": "firefox", 04:47:17 INFO - "script_repo_revision": "production", 04:47:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:47:17 INFO - "repository": "", 04:47:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:47:17 INFO - "buildid": "20160206044526", 04:47:17 INFO - "stage_platform": "macosx64", 04:47:17 INFO - "basedir": "/builds/slave/test", 04:47:17 INFO - "buildnumber": 308, 04:47:17 INFO - "platform": "yosemite_r7", 04:47:17 INFO - "mozci_request": { 04:47:17 INFO - "priority": -1, 04:47:17 INFO - "type": "trigger_all_talos_jobs", 04:47:17 INFO - "times": 6 04:47:17 INFO - }, 04:47:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:47:17 INFO - "slavebuilddir": "test", 04:47:17 INFO - "branch": "fx-team", 04:47:17 INFO - "revision": "97c27a348f09", 04:47:17 INFO - "slavename": "t-yosemite-r7-0112", 04:47:17 INFO - "builduid": "f0a6fa61c38e4edca5487dadb9c55727", 04:47:17 INFO - "repo_path": "integration/fx-team" 04:47:17 INFO - } 04:47:17 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.dmg. 04:47:17 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/test_packages.json. 04:47:17 INFO - Running post-action listener: _resource_record_post_action 04:47:17 INFO - ##### 04:47:17 INFO - ##### Running download-and-extract step. 04:47:17 INFO - ##### 04:47:17 INFO - Running pre-action listener: _resource_record_pre_action 04:47:17 INFO - Running main action method: download_and_extract 04:47:17 INFO - mkdir: /builds/slave/test/build/tests 04:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/test_packages.json 04:47:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/test_packages.json to /builds/slave/test/build/test_packages.json 04:47:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:47:18 INFO - Downloaded 1277 bytes. 04:47:18 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:47:18 INFO - Using the following test package requirements: 04:47:18 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 04:47:18 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:47:18 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 04:47:18 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:47:18 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 04:47:18 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:47:18 INFO - u'jsshell-mac.zip'], 04:47:18 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:47:18 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 04:47:18 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 04:47:18 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:47:18 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 04:47:18 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:47:18 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 04:47:18 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:47:18 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 04:47:18 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 04:47:18 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 04:47:18 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 04:47:18 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.common.tests.zip 04:47:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 04:47:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:18 INFO - Downloaded 17118726 bytes. 04:47:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:47:21 INFO - Return code: 0 04:47:21 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:47:21 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')]} 04:47:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.common.tests.zip 04:47:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 04:47:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:21 INFO - Downloaded 17118726 bytes. 04:47:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:47:25 INFO - Return code: 0 04:47: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')]} 04:47:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.talos.tests.zip 04:47:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 04:47:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:47:25 INFO - Downloaded 10706582 bytes. 04:47:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:47:25 INFO - Return code: 0 04:47: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')]} 04:47:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.dmg 04:47:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 04:47:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 04:47:30 INFO - Downloaded 111105630 bytes. 04:47:30 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.dmg 04:47:30 INFO - mkdir: /builds/slave/test/properties 04:47:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:30 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:30 INFO - Contents: 04:47:30 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.dmg 04:47:30 INFO - Running post-action listener: _resource_record_post_action 04:47:30 INFO - Running post-action listener: set_extra_try_arguments 04:47:30 INFO - ##### 04:47:30 INFO - ##### Running populate-webroot step. 04:47:30 INFO - ##### 04:47:30 INFO - Running pre-action listener: _resource_record_pre_action 04:47:30 INFO - Running main action method: populate_webroot 04:47:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:30 INFO - '--tppagecycles', 04:47:30 INFO - '7'], 04:47:30 INFO - 'tests': ['tsvgm']}}, 04:47:30 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:47:30 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:47:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:30 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 04:47:30 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:47:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:30 INFO - 'talos_options': ['--e10s'], 04:47:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:30 INFO - 'tests': ['damp', 'tps']}, 04:47:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:30 INFO - 'talos_options': ['--e10s'], 04:47:30 INFO - 'tests': ['damp', 'tps']}, 04:47:30 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:47:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:30 INFO - 'other': {'tests': ['a11yr', 04:47:30 INFO - 'ts_paint', 04:47:30 INFO - 'tpaint', 04:47:30 INFO - 'sessionrestore', 04:47:30 INFO - 'sessionrestore_no_auto_restore']}, 04:47:30 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:47:30 INFO - 'tests': ['a11yr', 04:47:30 INFO - 'ts_paint', 04:47:30 INFO - 'tpaint', 04:47:30 INFO - 'sessionrestore', 04:47:30 INFO - 'sessionrestore_no_auto_restore']}, 04:47:30 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:47:30 INFO - 'tests': ['a11yr', 04:47:30 INFO - 'ts_paint', 04:47:30 INFO - 'tpaint', 04:47:30 INFO - 'sessionrestore', 04:47:30 INFO - 'sessionrestore_no_auto_restore']}, 04:47:30 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:47:30 INFO - 'tests': ['a11yr', 04:47:30 INFO - 'ts_paint', 04:47:30 INFO - 'tpaint', 04:47:30 INFO - 'sessionrestore', 04:47:30 INFO - 'sessionrestore_no_auto_restore']}, 04:47:30 INFO - 'other_l64': {'tests': ['a11yr', 04:47:30 INFO - 'ts_paint', 04:47:30 INFO - 'tpaint', 04:47:30 INFO - 'sessionrestore', 04:47:30 INFO - 'sessionrestore_no_auto_restore']}, 04:47:30 INFO - 'other_nol64': {'tests': ['a11yr', 04:47:30 INFO - 'ts_paint', 04:47:30 INFO - 'tpaint', 04:47:30 INFO - 'sessionrestore', 04:47:30 INFO - 'sessionrestore_no_auto_restore']}, 04:47:30 INFO - 'svgr': {'tests': ['tsvgx', 04:47:30 INFO - 'tsvgr_opacity', 04:47:30 INFO - 'tart', 04:47:30 INFO - 'tscrollx', 04:47:30 INFO - 'cart']}, 04:47:30 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:47:30 INFO - 'tests': ['tsvgx', 04:47:30 INFO - 'tsvgr_opacity', 04:47:30 INFO - 'tart', 04:47:30 INFO - 'tscrollx', 04:47:30 INFO - 'cart']}, 04:47:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:30 INFO - 'tests': ['tp5o']}, 04:47:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:30 INFO - 'talos_options': ['--e10s'], 04:47:30 INFO - 'tests': ['tp5o']}, 04:47:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:30 INFO - 'talos_options': ['--xperf_path', 04:47:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:30 INFO - 'tests': ['tp5n']}, 04:47:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:30 INFO - 'talos_options': ['--e10s', 04:47:30 INFO - '--xperf_path', 04:47:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:30 INFO - 'tests': ['tp5n']}}, 04:47:30 INFO - 'talos.zip': {'path': '', 04:47:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:30 INFO - Running post-action listener: _resource_record_post_action 04:47:30 INFO - ##### 04:47:30 INFO - ##### Running create-virtualenv step. 04:47:30 INFO - ##### 04:47:30 INFO - Running pre-action listener: _resource_record_pre_action 04:47:30 INFO - Running main action method: create_virtualenv 04:47:30 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:30 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 04:47:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:30 INFO - Using real prefix '/tools/python27' 04:47:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:31 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:34 INFO - Installing pip.................done. 04:47:34 INFO - Return code: 0 04:47:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:34 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 0x10c8f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8fe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57058de60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, '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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:34 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 04:47:34 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 04:47:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:47:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:34 INFO - 'HOME': '/Users/cltbld', 04:47:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:34 INFO - 'LOGNAME': 'cltbld', 04:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:34 INFO - 'MOZ_NO_REMOTE': '1', 04:47:34 INFO - 'NO_EM_RESTART': '1', 04:47:34 INFO - 'PAGER': '/bin/cat', 04:47:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:34 INFO - 'PWD': '/builds/slave/test', 04:47:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:34 INFO - 'SHELL': '/bin/bash', 04:47:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:47:34 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:47:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:34 INFO - 'USER': 'cltbld', 04:47:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:34 INFO - 'XPC_FLAGS': '0x0', 04:47:34 INFO - 'XPC_SERVICE_NAME': '0', 04:47:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:34 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 04:47:34 INFO - Cleaning up... 04:47:34 INFO - Return code: 0 04:47:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:34 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 0x10c8f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8fe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57058de60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, '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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:34 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 04:47:34 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 04:47:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:47:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:34 INFO - 'HOME': '/Users/cltbld', 04:47:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:34 INFO - 'LOGNAME': 'cltbld', 04:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:34 INFO - 'MOZ_NO_REMOTE': '1', 04:47:34 INFO - 'NO_EM_RESTART': '1', 04:47:34 INFO - 'PAGER': '/bin/cat', 04:47:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:34 INFO - 'PWD': '/builds/slave/test', 04:47:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:34 INFO - 'SHELL': '/bin/bash', 04:47:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:47:34 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:47:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:34 INFO - 'USER': 'cltbld', 04:47:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:34 INFO - 'XPC_FLAGS': '0x0', 04:47:34 INFO - 'XPC_SERVICE_NAME': '0', 04:47:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:34 INFO - Downloading/unpacking psutil>=0.7.1 04:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:37 INFO - Installing collected packages: psutil 04:47:37 INFO - Running setup.py install for psutil 04:47:38 INFO - building 'psutil._psutil_osx' extension 04:47:38 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 04:47:38 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 04:47:38 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 04:47:38 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 04:47:38 INFO - building 'psutil._psutil_posix' extension 04:47:38 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 04:47:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:47:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:47:38 INFO - ^ 04:47:38 INFO - 1 warning generated. 04:47:38 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 04:47:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:38 INFO - Successfully installed psutil 04:47:38 INFO - Cleaning up... 04:47:38 INFO - Return code: 0 04:47:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:38 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 0x10c8f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8fe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57058de60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, '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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:38 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 04:47:38 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 04:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:38 INFO - 'HOME': '/Users/cltbld', 04:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:38 INFO - 'LOGNAME': 'cltbld', 04:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:38 INFO - 'MOZ_NO_REMOTE': '1', 04:47:38 INFO - 'NO_EM_RESTART': '1', 04:47:38 INFO - 'PAGER': '/bin/cat', 04:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:38 INFO - 'PWD': '/builds/slave/test', 04:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:38 INFO - 'SHELL': '/bin/bash', 04:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:47:38 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:38 INFO - 'USER': 'cltbld', 04:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:38 INFO - 'XPC_FLAGS': '0x0', 04:47:38 INFO - 'XPC_SERVICE_NAME': '0', 04:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:47:39 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 04:47:39 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 04:47:39 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 04:47:39 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 04:47:39 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 04:47:39 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 04:47:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:47:41 INFO - Installing collected packages: mozsystemmonitor 04:47:41 INFO - Running setup.py install for mozsystemmonitor 04:47:41 INFO - Successfully installed mozsystemmonitor 04:47:41 INFO - Cleaning up... 04:47:41 INFO - Return code: 0 04:47:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47:41 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')]} 04:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:41 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')]} 04:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:41 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 0x10c8f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8fe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57058de60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, '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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:41 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 04:47:41 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 04:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:41 INFO - 'HOME': '/Users/cltbld', 04:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:41 INFO - 'LOGNAME': 'cltbld', 04:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:41 INFO - 'MOZ_NO_REMOTE': '1', 04:47:41 INFO - 'NO_EM_RESTART': '1', 04:47:41 INFO - 'PAGER': '/bin/cat', 04:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:41 INFO - 'PWD': '/builds/slave/test', 04:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:41 INFO - 'SHELL': '/bin/bash', 04:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:47:41 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:41 INFO - 'USER': 'cltbld', 04:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:41 INFO - 'XPC_FLAGS': '0x0', 04:47:41 INFO - 'XPC_SERVICE_NAME': '0', 04:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:41 INFO - Downloading/unpacking blobuploader==1.2.4 04:47:41 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 04:47:41 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 04:47:41 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 04:47:41 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 04:47:41 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 04:47:41 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 04:47:44 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47:44 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 04:47:44 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 04:47:44 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 04:47:44 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 04:47:44 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 04:47:44 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 04:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47:44 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 04:47:44 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 04:47:44 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 04:47:44 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 04:47:44 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 04:47:44 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 04:47:45 INFO - Downloading docopt-0.6.1.tar.gz 04:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:45 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:45 INFO - Running setup.py install for blobuploader 04:47:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:45 INFO - Running setup.py install for requests 04:47:46 INFO - Running setup.py install for docopt 04:47:46 INFO - Successfully installed blobuploader requests docopt 04:47:46 INFO - Cleaning up... 04:47:46 INFO - Return code: 0 04:47:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:46 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 0x10c8f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8fe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57058de60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, '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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:46 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 04:47:46 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 04:47:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:47:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:46 INFO - 'HOME': '/Users/cltbld', 04:47:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:46 INFO - 'LOGNAME': 'cltbld', 04:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:46 INFO - 'MOZ_NO_REMOTE': '1', 04:47:46 INFO - 'NO_EM_RESTART': '1', 04:47:46 INFO - 'PAGER': '/bin/cat', 04:47:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:46 INFO - 'PWD': '/builds/slave/test', 04:47:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:46 INFO - 'SHELL': '/bin/bash', 04:47:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:47:46 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:47:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:46 INFO - 'USER': 'cltbld', 04:47:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:46 INFO - 'XPC_FLAGS': '0x0', 04:47:46 INFO - 'XPC_SERVICE_NAME': '0', 04:47:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:46 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-3Zsfmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:46 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-h1Pi4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:46 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-n3VAuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:46 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Tgotal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:46 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-mpOMTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-3U3nF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-q0DEhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-v9PfyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-2ZdZ5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-wswJnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-mmpyyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Mo922K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-6NhAW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-60TJ8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:47 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-bntL7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:48 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-QgxUK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:48 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-PSSQN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:48 INFO - Running setup.py install for manifestparser 04:47:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:48 INFO - Running setup.py install for mozcrash 04:47:48 INFO - Running setup.py install for mozdebug 04:47:48 INFO - Running setup.py install for mozdevice 04:47:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:48 INFO - Running setup.py install for mozfile 04:47:48 INFO - Running setup.py install for mozhttpd 04:47:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for mozinfo 04:47:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for mozInstall 04:47:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for mozleak 04:47:49 INFO - Running setup.py install for mozlog 04:47:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for moznetwork 04:47:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:49 INFO - Running setup.py install for mozprocess 04:47:50 INFO - Running setup.py install for mozprofile 04:47:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Running setup.py install for mozrunner 04:47:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Running setup.py install for mozscreenshot 04:47:50 INFO - Running setup.py install for moztest 04:47:50 INFO - Running setup.py install for mozversion 04:47:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:50 INFO - Cleaning up... 04:47:50 INFO - Return code: 0 04:47:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:50 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 0x10c8f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8fe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57058de60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, '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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:50 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 04:47:50 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 04:47:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:47:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:50 INFO - 'HOME': '/Users/cltbld', 04:47:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:50 INFO - 'LOGNAME': 'cltbld', 04:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:50 INFO - 'MOZ_NO_REMOTE': '1', 04:47:50 INFO - 'NO_EM_RESTART': '1', 04:47:50 INFO - 'PAGER': '/bin/cat', 04:47:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:50 INFO - 'PWD': '/builds/slave/test', 04:47:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:50 INFO - 'SHELL': '/bin/bash', 04:47:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:47:50 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:47:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:50 INFO - 'USER': 'cltbld', 04:47:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:50 INFO - 'XPC_FLAGS': '0x0', 04:47:50 INFO - 'XPC_SERVICE_NAME': '0', 04:47:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-sKzCNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:51 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)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-6btlaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ryhyAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:51 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)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-PwoAxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:51 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)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-6Eienb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:51 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)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-FjaUl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:51 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)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-uBlUCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:51 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)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-MJTrwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:51 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)) 04:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-1sSlw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:52 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)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:52 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-f39c0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:52 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)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:52 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-npiu2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:52 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)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:52 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-dVFDZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:52 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)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:52 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-V_0JmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:52 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)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:52 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-tGxg8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:52 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)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:52 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-4SH3Ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:52 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)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:52 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-indPsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:52 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)) 04:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:52 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-aa5GcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:52 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)) 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:47:52 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)) 04:47:52 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)) 04:47:52 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)) 04:47:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:55 INFO - Downloading blessings-1.6.tar.gz 04:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:55 INFO - Installing collected packages: blessings 04:47:55 INFO - Running setup.py install for blessings 04:47:55 INFO - Successfully installed blessings 04:47:55 INFO - Cleaning up... 04:47:55 INFO - Return code: 0 04:47:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:55 INFO - Reading from file tmpfile_stdout 04:47:55 INFO - Current package versions: 04:47:55 INFO - blessings == 1.6 04:47:55 INFO - blobuploader == 1.2.4 04:47:55 INFO - docopt == 0.6.1 04:47:55 INFO - manifestparser == 1.1 04:47:55 INFO - mozInstall == 1.12 04:47:55 INFO - mozcrash == 0.16 04:47:55 INFO - mozdebug == 0.1 04:47:55 INFO - mozdevice == 0.48 04:47:55 INFO - mozfile == 1.2 04:47:55 INFO - mozhttpd == 0.7 04:47:55 INFO - mozinfo == 0.9 04:47:55 INFO - mozleak == 0.1 04:47:55 INFO - mozlog == 3.1 04:47:55 INFO - moznetwork == 0.27 04:47:55 INFO - mozprocess == 0.22 04:47:55 INFO - mozprofile == 0.28 04:47:55 INFO - mozrunner == 6.11 04:47:55 INFO - mozscreenshot == 0.1 04:47:55 INFO - mozsystemmonitor == 0.0 04:47:55 INFO - moztest == 0.7 04:47:55 INFO - mozversion == 1.4 04:47:55 INFO - psutil == 3.1.1 04:47:55 INFO - requests == 1.2.3 04:47:55 INFO - wsgiref == 0.1.2 04:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:55 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 0x10c8f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8fe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57058de60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, '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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:55 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 04:47:55 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 04:47:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:47:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:55 INFO - 'HOME': '/Users/cltbld', 04:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:55 INFO - 'LOGNAME': 'cltbld', 04:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:55 INFO - 'MOZ_NO_REMOTE': '1', 04:47:55 INFO - 'NO_EM_RESTART': '1', 04:47:55 INFO - 'PAGER': '/bin/cat', 04:47:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:55 INFO - 'PWD': '/builds/slave/test', 04:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:55 INFO - 'SHELL': '/bin/bash', 04:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:47:55 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:55 INFO - 'USER': 'cltbld', 04:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:55 INFO - 'XPC_FLAGS': '0x0', 04:47:55 INFO - 'XPC_SERVICE_NAME': '0', 04:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:56 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)) 04:47:56 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)) 04:47:56 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)) 04:47:56 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)) 04:47:56 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)) 04:47:56 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)) 04:47:56 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)) 04:47:56 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)) 04:47:56 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)) 04:47:56 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)) 04:47:56 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)) 04:47:56 INFO - Cleaning up... 04:47:56 INFO - Return code: 0 04:47:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8fe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57058de60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, '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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:56 INFO - 'HOME': '/Users/cltbld', 04:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:56 INFO - 'LOGNAME': 'cltbld', 04:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:56 INFO - 'MOZ_NO_REMOTE': '1', 04:47:56 INFO - 'NO_EM_RESTART': '1', 04:47:56 INFO - 'PAGER': '/bin/cat', 04:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:56 INFO - 'PWD': '/builds/slave/test', 04:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:56 INFO - 'SHELL': '/bin/bash', 04:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:47:56 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:56 INFO - 'USER': 'cltbld', 04:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:56 INFO - 'XPC_FLAGS': '0x0', 04:47:56 INFO - 'XPC_SERVICE_NAME': '0', 04:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:56 INFO - Downloading/unpacking jsonschema 04:47:56 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 04:47:56 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 04:47:56 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 04:47:56 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 04:47:56 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 04:47:56 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 04:47:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:58 INFO - Downloading/unpacking functools32 (from jsonschema) 04:47:58 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 04:47:58 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 04:47:58 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 04:47:58 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 04:47:58 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 04:47:58 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 04:47:58 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:59 INFO - warning: no files found matching '*.txt' 04:47:59 INFO - no previously-included directories found matching 'build' 04:47:59 INFO - no previously-included directories found matching 'dist' 04:47:59 INFO - no previously-included directories found matching '.git*' 04:47:59 INFO - Installing collected packages: jsonschema, functools32 04:47:59 INFO - Running setup.py install for functools32 04:47:59 INFO - warning: no files found matching '*.txt' 04:47:59 INFO - no previously-included directories found matching 'build' 04:47:59 INFO - no previously-included directories found matching 'dist' 04:47:59 INFO - no previously-included directories found matching '.git*' 04:47:59 INFO - Successfully installed jsonschema functools32 04:47:59 INFO - Cleaning up... 04:47:59 INFO - Return code: 0 04:47:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8fe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57058de60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, '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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 04:47:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:47:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:47:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:59 INFO - 'HOME': '/Users/cltbld', 04:47:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:59 INFO - 'LOGNAME': 'cltbld', 04:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:59 INFO - 'MOZ_NO_REMOTE': '1', 04:47:59 INFO - 'NO_EM_RESTART': '1', 04:47:59 INFO - 'PAGER': '/bin/cat', 04:47:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:59 INFO - 'PWD': '/builds/slave/test', 04:47:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:59 INFO - 'SHELL': '/bin/bash', 04:47:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:47:59 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:47:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:59 INFO - 'USER': 'cltbld', 04:47:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:59 INFO - 'XPC_FLAGS': '0x0', 04:47:59 INFO - 'XPC_SERVICE_NAME': '0', 04:47:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:59 INFO - Downloading/unpacking flake8 04:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:48:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:48:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:48:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:48:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:48:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:48:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:48:03 INFO - no previously-included directories found matching 'docs/_build' 04:48:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:48:03 INFO - Running setup.py install for pep8 04:48:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:48:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:48:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:48:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:48:03 INFO - no previously-included directories found matching 'docs/_build' 04:48:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:48:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:48:03 INFO - Cleaning up... 04:48:03 INFO - Return code: 0 04:48:03 INFO - Running post-action listener: _resource_record_post_action 04:48:03 INFO - Running post-action listener: _start_resource_monitoring 04:48:03 INFO - Starting resource monitoring. 04:48:03 INFO - ##### 04:48:03 INFO - ##### Running install step. 04:48:03 INFO - ##### 04:48:03 INFO - Running pre-action listener: _resource_record_pre_action 04:48:03 INFO - Running main action method: install 04:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:03 INFO - Reading from file tmpfile_stdout 04:48:03 INFO - Detecting whether we're running mozinstall >=1.0... 04:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:48:03 INFO - Reading from file tmpfile_stdout 04:48:03 INFO - Output received: 04:48:03 INFO - Usage: mozinstall [options] installer 04:48:03 INFO - Options: 04:48:03 INFO - -h, --help show this help message and exit 04:48:03 INFO - -d DEST, --destination=DEST 04:48:03 INFO - Directory to install application into. [default: 04:48:03 INFO - "/builds/slave/test"] 04:48:03 INFO - --app=APP Application being installed. [default: firefox] 04:48:03 INFO - mkdir: /builds/slave/test/build/application 04:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:48:38 INFO - Reading from file tmpfile_stdout 04:48:38 INFO - Output received: 04:48:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:48:38 INFO - Running post-action listener: _resource_record_post_action 04:48:38 INFO - ##### 04:48:38 INFO - ##### Running run-tests step. 04:48:38 INFO - ##### 04:48:38 INFO - Running pre-action listener: _resource_record_pre_action 04:48:38 INFO - Running main action method: run_tests 04:48:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:38 INFO - 2016-02-06 04:48:38.381 screenresolution[2057:15374] starting screenresolution argv=screenresolution get 04:48:38 INFO - 2016-02-06 04:48:38.384 screenresolution[2057:15374] Display 0: 1600x1200x32@60 04:48:38 INFO - 2016-02-06 04:48:38.390 screenresolution[2058:15377] starting screenresolution argv=screenresolution list 04:48:38 INFO - Available Modes on Display 0 04:48:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:38 INFO - Intel Iris: 04:48:38 INFO - Chipset Model: Intel Iris 04:48:38 INFO - Type: GPU 04:48:38 INFO - Bus: Built-In 04:48:38 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:38 INFO - Vendor: Intel (0x8086) 04:48:38 INFO - Device ID: 0x0a2e 04:48:38 INFO - Revision ID: 0x0009 04:48:38 INFO - Displays: 04:48:38 INFO - Display: 04:48:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:38 INFO - Display Serial Number: 1600x1200 60 04:48:38 INFO - Main Display: Yes 04:48:38 INFO - Mirror: Off 04:48:38 INFO - Online: Yes 04:48:38 INFO - Rotation: Supported 04:48:38 INFO - Return code: 0 04:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:48:38 INFO - Python 2.7.3 04:48:38 INFO - Return code: 0 04:48:38 INFO - grabbing minidump binary from tooltool 04:48:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:38 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 0x10c79cd30>, '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 0x10c5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c792b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:48:38 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 04:48:38 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 04:48:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:48:38 INFO - Return code: 0 04:48:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:48:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:48:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:48:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:48:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:48:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:48:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:48:39 INFO - Return code: 0 04:48:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 04:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0112 --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 04:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1yd5K0JQgW/Render', 04:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:39 INFO - 'HOME': '/Users/cltbld', 04:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:39 INFO - 'LOGNAME': 'cltbld', 04:48:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:48:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:39 INFO - 'MOZ_NO_REMOTE': '1', 04:48:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:48:39 INFO - 'NO_EM_RESTART': '1', 04:48:39 INFO - 'PAGER': '/bin/cat', 04:48:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:39 INFO - 'PWD': '/builds/slave/test', 04:48:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:39 INFO - 'SHELL': '/bin/bash', 04:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YJZ7q9Q4te/Listeners', 04:48:39 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 04:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:39 INFO - 'USER': 'cltbld', 04:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:39 INFO - 'XPC_FLAGS': '0x0', 04:48:39 INFO - 'XPC_SERVICE_NAME': '0', 04:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 04:48:40 INFO - mozversion application_buildid: 20160203070736 04:48:40 INFO - mozversion application_changeset: 97c27a348f0963da5bce6b667612e7ba2d209078 04:48:40 INFO - mozversion application_display_name: Nightly 04:48:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:48:40 INFO - mozversion application_name: Firefox 04:48:40 INFO - mozversion application_remotingname: firefox 04:48:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:48:40 INFO - mozversion application_vendor: Mozilla 04:48:40 INFO - mozversion application_version: 47.0a1 04:48:40 INFO - mozversion platform_buildid: 20160203070736 04:48:40 INFO - mozversion platform_changeset: 97c27a348f0963da5bce6b667612e7ba2d209078 04:48:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:48:40 INFO - mozversion platform_version: 47.0a1 04:48:40 INFO - using testdate: 1454762920 04:48:40 INFO - actual date: 1454762920 04:48:40 INFO - starting webserver on 'localhost:51403' 04:48:40 INFO - SUITE-START | Running 2 tests 04:48:40 INFO - TEST-START | dromaeo_css 04:48:40 INFO - operating with platform_type : mac_ 04:48:40 INFO - Initialising browser for dromaeo_css test... 04:48:40 INFO - TEST-INFO | started process 2076 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpkRpyDN/profile http://localhost:51403/getInfo.html) 04:48:40 INFO - PROCESS | 2076 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 04:48:42 INFO - PROCESS | 2076 | __metrics Screen width/height:1600/1200 04:48:42 INFO - PROCESS | 2076 | colorDepth:24 04:48:42 INFO - PROCESS | 2076 | Browser inner width/height: 1024/689 04:48:42 INFO - PROCESS | 2076 | __metrics 04:48:42 INFO - PROCESS | 2076 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:48:42 INFO - PROCESS | 2076 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:48:42 INFO - PROCESS | 2076 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:48:43 INFO - TEST-INFO | 2076: exit 0 04:48:43 INFO - Browser initialized. 04:48:43 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:43 INFO - TEST-INFO | started process 2079 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpkRpyDN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:55 INFO - PROCESS | 2079 | RSS: Main: 243040256 04:48:55 INFO - PROCESS | 2079 | 04:49:46 INFO - PROCESS | 2079 | 1454762986339 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] 04:51:56 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51403/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51403/tests/dromaeo/cssquery-ext.html) 04:51:58 INFO - PROCESS | 2079 | RSS: Main: 413958144 04:51:58 INFO - PROCESS | 2079 | 04:55:00 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51403/tests/dromaeo/cssquery-ext.html (next: http://localhost:51403/tests/dromaeo/cssquery-jquery.html) 04:55:01 INFO - PROCESS | 2079 | RSS: Main: 384499712 04:55:01 INFO - PROCESS | 2079 | 04:58:03 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51403/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51403/tests/dromaeo/cssquery-mootools.html) 04:58:04 INFO - PROCESS | 2079 | RSS: Main: 377864192 04:58:04 INFO - PROCESS | 2079 | 05:01:06 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51403/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51403/tests/dromaeo/cssquery-prototype.html) 05:01:07 INFO - PROCESS | 2079 | RSS: Main: 374530048 05:01:07 INFO - PROCESS | 2079 | 05:04:10 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51403/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51403/tests/dromaeo/cssquery-yui.html) 05:04:10 INFO - PROCESS | 2079 | RSS: Main: 346341376 05:04:10 INFO - PROCESS | 2079 | 05:07:13 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51403/tests/dromaeo/cssquery-yui.html (next: http://localhost:51403/tests/dromaeo/cssquery-dojo.html) 05:07:13 INFO - PROCESS | 2079 | RSS: Main: 385634304 05:07:13 INFO - PROCESS | 2079 | 05:07:13 INFO - PROCESS | 2079 | __start_tp_report 05:07:13 INFO - PROCESS | 2079 | _x_x_mozilla_page_load 05:07:13 INFO - PROCESS | 2079 | _x_x_mozilla_page_load_details 05:07:13 INFO - PROCESS | 2079 | |i|pagename|runs| 05:07:13 INFO - PROCESS | 2079 | |0;dojo.html;3418.8461519231632;3444.7933124012575;3490.319387719395;3499;3531.876384326549;5602.355729091146;5644.6330988485715;5692.800751973677;5698.230738850254;5721.9999999999945;5589.133684278943;5693.146028095788;5755.453231942969;5870.530357571395;5879.11813228016;5151.253068305094;5162.096633089213;5186.818461353853;5190.740462976855;5221.843344699665;4767.904641907177;5285.339332583427;5314;5320.76056577455;5332.600054995872;4285.721428107194;5127.359080114986;5178.896422071575;5254.894902101936;5265.104932161523;5118.053160165344;5140;5185.092608793446;5191.844244672666;5220.738963051832;5117.795288188468;5162.586993040585;5186.662866913639;5192.299039629639;5236.581073514109;5520.806771763009;5613.298337707786;5634.577406694514;5649.661020338793;5656.462636049569;6161.137440758297;6191.442770150663;6197.783077592263;6198.659073750942;6204.534659900525;4631.62946964242;6292.1820163378925;6303;6307.621542707407;6331.14529538511;5789.189513468118;5800.245968024172;5802.593818432669;5828.242328497268;5837.328707198684;6199.132121502993;6211.844703882349;6280.371962803774;6308.589941653778;6313.842153946188;6103.176071230416;6106.885399019288;6160.168377269011;6182.165407669997;6183.412575805291;6250.937490625036;6265.373462653698;6272.247330320391;6281.309056003836;6293.118963345135;6109.450149486567;6136.355682653347;6156.753729850845;6171.259448866164;6188.350223226496;5172;6111.083337499411;6213.409726076015;6229.937700623026;6250.562460627803;4828.903421931612;4844.563989240985;4853.2720091985775;4875.8049678012485;4902.2646603009125;4285.250081235771;4361.629261512743;4370.104128653618;4443.4445694288215;4447.910261985834;5754.223179870748;5783.971080144573;5789.218455508537;5796.942030579725;5831.096180092092;5840.737166827503;6001.009833377444;6244.188255526843;6248.9062664059165;6294.339094395114;5547.5007249347755;5685.175649530877;5685.630434021776;5728.341240757283;5756.5682573807135;5312.309399778082;5354.518093371616;5356.705381204071;5360.865978350573;5400.243965844785;6274.498040156776;6325;6329.588576742497;6331.081993110973;6358.23701155855;6161.476274516537;6244.812655620339;6284.24589049317;6299.055141728777;6315.463185629273;6197.845053873689;6283.057541368831;6315.5263355247625;6322.936770632235;6344.175257216532;6142.662153581596;6207;6218.502519798315;6234;6235.937640623536;6128.167033931239;6162.075688646739;6262.968685156728;6301.905471417841;6310.463610592968;6052.364501727256;6093.451589356979;6094.299155596986;6098.481629061579;6103.176071230327;6065;6105.48103411215;6106.542009349406;6108.17539632144;6110.327863935053;5890;5933.317668468008;5994.13085102649;6044.395560443921;6051.818445446467;5345.839624811262;6080.209572755691;6105.45050945417;6109.511239100772;6124.127207212446;5854.121881717776;5891.086881533369;5897.3807750185615;5914.556408269311;5953.345132035386 05:07:13 INFO - PROCESS | 2079 | |1;ext.html;455.3465776610562;476.68062398193206;575.7610591604482;607.6809674920669;612.4212619074974;28918.132456026327;29233.41533169335;36445.27109457813;37274.44088338677;37304.25391492168;39502.80248598753;39781.60218397815;39787.20425591486;39798;39811.80094099533;9357.34498585101;9588;9593.66422175224;9604.51977401127;9608.087231712976;8376.623051962677;9513.048695130467;9636.855447168298;9637;9654.662086826962;9510.619575216982;9766.658166964122;9767.609295628203;9767.853482197772;9771.853422198672;9685.32202745808;9743.853842192337;9746.756331091708;9761.365511241818;9763.170130538912;7957.482763620346;9574.377665451793;9583.808323833484;9585.424874507551;9585.568649410794;38002.23995520074;38420;38438;38488.422673659916;38489.03777405559;3156.9684303157132;3197.936041279185;3200.199950012497;3202.9199270018203;3210.101171671912;27391.72608273912;27558.724412756015;27685.30786730348;28179.013734519398;28392.432151356858;2615.699194592627;2647.8014148938714;2650.1519513755607;2803.929901752452;2812.4937511247967;481671;482652;483387;483491;483884;51129.48870511322;51968.22047669288;58646.12030819541;59107.70446147742;59390.10914836281;49132;63062;63853.36146638567;63914;63942;13159.60521184366;15664.530064098095;15885.920570397075;15887.52337429879;15896.602584935468;11357.204995650225;11503.07975361969;12167.817482737766;12306.323152226714;12309.876901231051;15396.23018849053;18481.630367392576;18570.44288671342;18596.163172657263;18654.440366788745;7474.476786624884;7547.73582924595;7693;7717.262928134734;7896.933913921662;920.2959735802117;949.7245798718433;971.7279161834698;1053.562771449855;1092.5738961804902;24249.75750242475;28041.71958280432;28379.290517737223;28722.856385718354;28853;1062.389126252408;1115.2806439846254;1159.478234794346;1166.1370585766472;1176.1943068997764;14397.78403323951;14459.349329280207;14506.1296322221;14808.703825923421;14884.925575372054;501428;503496;505035.4748226309;505514;505761.94238057884;54638.45361546334;55077.44922550723;55107.44892551103;55141.897162056535;55356.72321638366;1402.9088109272864;1412.498563010137;1430.3134495442246;1432.0405328429977;1437.8202724659418;23403.648945265493;26432.603510946967;29794.255143621584;30358.241043973194;30648.693513065475;51220.48779512306;51677;51710.22434663558;51882.22176667429;51962.700932475476;425757;428882;429003;429854;430222;29456.852715736284;29823.850880745456;30228.697713023466;30497.69502305037;30676.233094172825;42768.93077673083;42880;43395.783021084695;43879.34180987224;43882.56117438785;34686.47970280498;36596.63403365932;36603.63396366109;36915.261694766494;39897.40153897636;37144.07139821526;37150.256994860494;37480.250394992494;37909.43135853019;38063.42904856374;32951.50572741363;39402.60597393989;39718.2056358877;39774.60225397709;39789;36827;37306.62693373032;37433.625663744104;39018;39174.804125979186;23148.537029259656;24791.380215494035;26202.73797262003;26329.86835065889;26627 05:07:13 INFO - PROCESS | 2079 | |2;jquery.html;310.408671480829;344.68805730813625;355.6674509333773;361.6239111324223;364.8613526859791;14463.059901106433;14527.636809079779;15292.84707152928;19178.23287068519;19313.903430482827;18437.631247375044;18571.350002749907;18636.906815465936;19267.807321926775;19552.41342759716;4088.6729061674996;5503.257060296869;5579.330480342354;5718.056520674084;5845.09401042839;4272.530021697627;4316.158349121923;6099.725512351954;6375.171227740388;6404.039394090901;6062;6332.715027823759;6361.2048493938255;6378.075179099028;6386.840328991789;6179.66011869347;6324.146240257553;6377.075324078006;6380.712867920908;6388.456981156584;5531.502164805147;5541.33503979525;5543.667379957175;5548.72256387179;5552.444755524415;18941.621167576708;23899.40251493709;24283.63574546383;24407;24528.896199671057;2127.383058912929;2231.8995645196005;2563.3207200091992;2584.1213987244428;2883.35124596964;14475.131492110508;14592.90553208503;14690.853091469055;14818.259087045604;15001.174935378549;1630.5678995066316;1700.2688843797162;1702.0383483331877;1730.4203091964207;1747.9737803932824;94071.58892616617;96297.03702963021;96731;98540.02919941633;99532.50233748858;9544.761380965392;9659.71020869375;10162.237832162617;10371.533281002374;10556.63051793199;12594.622161335175;12689.555865544662;12702.555410560586;12730.554430594884;12730.872691273154;8412.62143203545;8424.199701028392;8446.83106337882;8516.531590762444;8527.10465401124;8640.956795216109;8647.654093836176;8656.61045252965;8735.301175905912;8746.475211487332;5602.579806514446;5853.678047707332;5865.2961644602065;6008.8197354078575;6126.908096378558;1418.638247246966;1754.736789481588;1771.4508502364308;1793.0944872839393;1814.4375243674504;855.8288342331558;903.263839970422;914.3142643017736;917.9173874351341;1038.7039693687263;16043.518694439186;16652.58368540772;19404.611907761766;19408.902955485133;20298.391048268575;921.3918813583138;955.1499165742495;986.8717066781272;988.8121256961155;1035.7100011996654;10005.299629025898;10917.454127293604;10978.835317470244;10983.560657573607;10991.670249892515;520827;600623;602920;658863;666240;59006.70496647575;80723.59638201771;86089;87224;89057.55471222602;1124.5951457475303;1140.4867809485766;1159.6752909185611;1165.2367699157066;1165.3648761425043;18410.35563755262;18771.34300299483;18871;19068.618627627093;19069;44833.55166448294;47568.52431475779;48163.5183648159;48308.516914830405;49120;64570.35429645643;65102;65122.69754604976;65850.67074664788;66434;6338.936610633835;7219.711211551479;7507.948887155693;7570.280823321776;7570.470067095251;11321.603743868925;11366.318020918772;11688.70778230551;12010.819837702616;12106;8445.873311900203;9902.25733070037;9933.552990115606;10078.042585954323;10105.24210684187;9746.9025309746;9958.850617240601;10002.699919002442;10122.44326562046;10217.897821021694;9051.547422628973;10333.379997200193;10447.410635310072;10519.684409467729;10614.695349452808;7519.285667861544;7644.541327520366;7660.233976602295;7662.195469475625;7668.156502784577;8324.833503329779;10207.591696332063;10378.896211037792;10432.843507347548;10508.42203678805 05:07:13 INFO - PROCESS | 2079 | |3;mootools.html;1360.591822453264;1399.1255465334166;1573.2212554785378;1757.7802774653169;1944.6013567218722;8055.355571554276;8272.917270827289;8621.051684314729;8775.47347159171;8803.64785408583;8569.228769410753;8617.784555386119;8738.519381434018;8750.431221970583;8789.472631642106;2170.175333373316;2234.519578290667;2458.91393801217;2483.3170878008514;2486.502699460106;2083.687446882972;2271.057511132878;2509.1594315904194;2509.8619575923317;2510.962335564968;2397.4485868250313;2450.571150048743;2454.7177074636465;2460.6309053641917;2474.158786012755;2411.722651895037;2445.8410203336816;2458.913938012161;2459.4220358215807;2472.431340791628;2060.5672808710187;2335.637976113705;2446.1805295226122;2455.300239431753;2457.3733697907096;8878.73363799087;8937.73186804397;8940.508272045035;8958.148975847284;8995.100489951017;5342.385625653061;5430.049741295257;5430.456954304577;5452.2094296327205;5478.315210598676;2939.9853000735075;3583.6595523425235;3737.0470530014672;3737.0657335666083;3759.0226541099237;1030.8814486334018;1051.3166441812807;1059.3220338983058;1081.4376524207446;1081.9613171355509;12125.939370303093;12187.085968552394;12200.328981905992;12220.694482637917;12241.877581224251;4947.208446648519;4951.207806750903;4960;5007.098722230034;5021.748912554358;10197.286189966779;10206.030427109412;10234.48827558619;10235.744106397251;10239.48802559869;5520.475554822286;5520.751566179531;5526.419725928719;5528.44715528452;5532.75102620375;4973.850784476472;5022.171341728647;5028.7737051832755;5041.1430056889685;5044;3652.7990960497427;3705.0366904605;3708.962910370916;3903.2435404067714;3985.103351745892;1285.1582213650074;1305.2951406240713;1321.9867801322057;1322.041519898066;1323.1664051647592;1300.681333073403;1330.4279159961116;1343.3619030960215;1358.4701966233176;1362.9114107583173;7993.8401231976195;8045.316148127357;8168.42821002536;8396.916030839613;8403.285720713686;1156.069364161841;1157.4212893553224;1172.3962159487871;1177.2053863642006;1194.2416565480983;6186.041163619646;6251.28110267316;6255.812325630239;6259.71831267594;6270.059491076284;10062.647807326855;10600.257981941344;10616.681499555025;10728.249022568345;10748.570057197781;9521.714348569416;10657.946710266398;10701.625443109453;10787;10811.135109191246;8206.876896846552;8315.916840831514;8340.541270230196;8350.498970061815;8369.3304535637;6741.898871516833;7813;7845.725399610987;7908.327792137733;7944.364450843803;9503.857442138511;9525.52372381392;9591.808163836544;9732.562034708313;9816.607335706492;7966.880496792669;10070.748231294276;10093.949530252301;10099.848502272618;10111.848322275318;7036.577805331696;7060.7881763547175;7149.749758758627;7154.856902862018;7166.498345116001;7318.890216646861;7502.549847009197;8165.346772258086;8264.793380165544;8287.088420273645;6384.457321127757;6965.33829286217;7183.389411900047;7737.148913619385;7911.723089691834;6974.37230649244;7055.835787095072;7172.677229524579;7560.735374261874;7788;7303.196648368781;7723.34351580122;7812;7812.218778122142;7818.296353328;7053.047838541731;7105.75129870452;7128.6792094356915;7139.071920650283;7179.9641001796745;6447.677616119269;7073.080499535027;7265.055542779405;7291.562506249642;7295.854082918206 05:07:13 INFO - PROCESS | 2079 | |4;prototype.html;308.82088388734536;316.58369244443554;329.89113592514474;330.51084395095245;331.7395844262254;6456.870862582746;6615.915986464261;6639.834004149892;6645.734170633181;6653.602743423875;6562.179727534059;6682.398584127428;6702.8659426811555;6711.5973041617535;6721.1598550181225;4367.606915377615;4436.334549817521;4776.546228108333;4850.927236091461;4879.829205977791;4848.7090774553635;4876.220179634425;4919;4924.80300787968;4934.851954441355;4586.77066146693;4639.072185562884;4705.458872229686;4716.50476699945;4797.088553174885;3838.366669499543;4756.453007904066;4764.356811830393;4767.69010014348;4775.42694876617;4068.3083875741195;4070;4072.7963601819793;4073.959260407388;4085.611866872642;6329.4619957303685;6333.7466501339895;6335.778247761306;6351.396617321347;6370.872582548323;5384.865378365533;5568.387477377485;5595.608307418483;5611.326640803089;5624.3531993820825;6942.826429339257;6949.339812717783;6950.374466298057;6951.582905025714;6952.374286314256;4273.55127711592;4362.847300344472;4522.728636281834;4533.206688829475;4543;5463.863403414906;7184;7308.780736577911;7342.926570734277;7435.4795164337975;3980.4626375438943;4579.656525760582;4583.404157459576;4584.404027476407;4589.403377560896;4643.142742902798;4649.790759415834;4651.069786042805;4653.348531205634;4674.789634466457;5363.088274993261;5519.641223320471;5596.132599447093;5617.691026993554;5617.831465056055;7043.92956070443;7047.330503602149;7048.823779405453;7059.047028651066;7064.116985376828;1603.1182849432764;1626.487656388234;1627.2839950421776;1627.788387509616;1628.7149748793913;862.4609618988133;1038.6416686243233;1046.549983507084;1051.9631812886512;1061.1881910338598;5659.971700141473;6613.669316534154;6633.800985970429;6644.501662375341;6652.368025037614;7302.123745150616;7323.7436689715605;7323.963380183065;7332.816679582947;7336.743213987592;6395.328490508523;6591.769288074895;6596.439302659328;6611.074449577063;6618.271990081087;3826.732128750961;3875.3799392097694;3875.7093218008763;3878.8448462061765;3885.86399476017;7611;7626.6186690665245;7629.580373079534;7633.580153091633;7640.770776876702;7231.877998765745;7519.586422746692;7522.398208143335;7534.736284230025;7543.811404714926;7616.657250423744;7618.695252189961;7619.885701714478;7630.8092297693;7642.121156067013;7300.926990730237;7301.525400848928;7304.8539029218055;7305;7306.159791624032;6924.6537673117155;7143.607101609461;7163.31948464895;7165.928340716526;7172.784816455514;7600.619969001417;7620.542767433972;7621.390288776774;7621.428392870446;7626.313631773167;4623.86128416148;4633.490316065163;4634.119517291704;4635.51327110662;4650.651201159994;5638.379778224474;7230.42156627479;7275.672594733144;7278.417726581966;7279.490435669452;7009.369156775914;7011.789646310619;7015.859682806214;7017.894731578928;7027.718891244497;6824.283450237654;6942.895856562257;7008.299170082951;7028.332308430691;7046.823829404101;6824.317568243136;6860.794176174722;6861.451083913175;6862.553933994274;6864.759733409307;5741.483266506035;7032.12098487689;7050.365467107985;7055;7060.3292687194635;6647.734090636513;6888.345607167311;6888.655567221719;6897.206821215625;6903.1371078615175 05:07:13 INFO - PROCESS | 2079 | |5;yui.html;665.181826353585;716.8136284566011;723.9601821899795;745.9701611935523;768.9577073260975;14581.125132492058;14853.55439336819;14866.033707809012;14868.77696834547;14872.033317834334;13426.39581218845;14806.481773137943;15098.924505377458;15435.305411256493;15462.381504739797;681.9045333653293;691.9550229235109;841.6633346661323;868.695956415256;869.1873098652749;688.102026854954;841.8947631546057;866.7399780065987;869.2090197919894;869.3132425383939;897.6723495923974;913.7532866126141;914.4513292024765;914.5427286356821;914.9542522873863;711.2602892991283;722.1406526233765;723.4935545118385;898.5148020069155;900.8783814185099;530.9097453432887;532.3133158225884;656.8226578823738;668.6289109544201;669.0198858672069;14788.70422591553;14824.703505929929;14833.777493337608;14854.925725371411;14873.33070011852;10401.323913945569;12692.555760548428;12729.681757956125;12830.230186188859;13102.934485327607;9990.300678952477;10054.962335075616;10121.342112762646;10130.442825644586;10154.28919975602;3531.7704349217474;3557.928841423183;3560.4481305397703;3561.0741207286032;3563.8574457021687;76006.23993760103;78134.60932695356;80070.99822504484;82220.30008649625;83042.16957830405;9612.567434465465;9615.5672994714;9657.661981830603;9686.564104615309;9975.950120249352;16852.41016564439;21407.57184856294;21430.678539821914;21449.571008580053;21480.355589332346;7624.19945905683;7651.464397492122;7768.572728499874;7907.9604601976625;7947.602619869099;4188.392683060939;4203.55862634419;4228.534861165331;4284.828606855754;4432.357308190359;3641;3680.392735198716;3684.392075307544;3689.9815500922323;3695.0577602711674;646.8479907221799;653.9509536784665;655.3872129558878;672.5762769455212;674.3593586093231;543.8939406815633;607.6475644126396;621.7142402403367;627.4353082226033;628.6448156791398;8388.203120703523;8519.659213631508;8605.35459840502;8647.135286471303;8729.432586881832;590.5541316306247;615.2094558492316;617.7405489694339;622.0575827621158;632.2634131237057;3936.7047471439755;3976.5029371328583;3985.4818873546255;4039.818208180639;4082.3468245080644;92344;93339.59990600146;94020.11959760904;94106.5294673536;95234;79019.8147027795;79580.6020969903;80130.9869013094;81154.59422702849;82047.5897620508;68693.65653171702;69372.61254774877;69699.99999999898;70167;70253;7899.0521137463875;7928.881066783887;8038.799030024296;8121.147279535564;8157.592120393838;49525.257121143935;49742.50257497523;49873.00253994827;50961;51014.7246318845;64052;66305.72802623663;66536.33463665302;66833.66583167053;67749.32250677627;10890.78218435611;10957.890421096006;10967.396793176453;11140.10879129684;11473.770524589294;11888.405579720806;11973.401329933295;11980.532756230365;11993.020724075119;12322.938385308016;10162.796744065225;11174.217804753589;11597.884021160018;11670.88329116698;11706.35615041181;11446.427678616203;11600.245984011011;11650.184487085822;11695.590654327398;11838.171328006956;11249.831252531056;11355.716107097389;11719.238249513754;11739.354335511629;11960.282383057045;8642.740717778477;9042.290234146927;9375.85936210971;9550.413798245507;9648.179904708179;9901.098354952364;10068.194544436574;10082.495875206358;10096.19230461576;10181.083702466814 05:07:13 INFO - PROCESS | 2079 | __end_tp_report 05:07:13 INFO - PROCESS | 2079 | __start_cc_report 05:07:13 INFO - PROCESS | 2079 | _x_x_mozilla_cycle_collect,1643 05:07:13 INFO - PROCESS | 2079 | __end_cc_report 05:07:13 INFO - PROCESS | 2079 | __startTimestamp1454764033692__endTimestamp 05:07:13 INFO - PROCESS | 2079 | 05:07:13 INFO - PROCESS | 2079 | ------- Summary: start ------- 05:07:13 INFO - PROCESS | 2079 | Number of tests: 6 05:07:13 INFO - PROCESS | 2079 | 05:07:13 INFO - PROCESS | 2079 | [#0] dojo.html Cycles:165 Average:5715.97 Median:5897.38 stddev:647.73 (11.0%) stddev-sans-first:624.15 05:07:13 INFO - PROCESS | 2079 | Values: 3418.8 3444.8 3490.3 3499.0 3531.9 5602.4 5644.6 5692.8 5698.2 5722.0 5589.1 5693.1 5755.5 5870.5 5879.1 5151.3 5162.1 5186.8 5190.7 5221.8 4767.9 5285.3 5314.0 5320.8 5332.6 4285.7 5127.4 5178.9 5254.9 5265.1 5118.1 5140.0 5185.1 5191.8 5220.7 5117.8 5162.6 5186.7 5192.3 5236.6 5520.8 5613.3 5634.6 5649.7 5656.5 6161.1 6191.4 6197.8 6198.7 6204.5 4631.6 6292.2 6303.0 6307.6 6331.1 5789.2 5800.2 5802.6 5828.2 5837.3 6199.1 6211.8 6280.4 6308.6 6313.8 6103.2 6106.9 6160.2 6182.2 6183.4 6250.9 6265.4 6272.2 6281.3 6293.1 6109.5 6136.4 6156.8 6171.3 6188.4 5172.0 6111.1 6213.4 6229.9 6250.6 4828.9 4844.6 4853.3 4875.8 4902.3 4285.3 4361.6 4370.1 4443.4 4447.9 5754.2 5784.0 5789.2 5796.9 5831.1 5840.7 6001.0 6244.2 6248.9 6294.3 5547.5 5685.2 5685.6 5728.3 5756.6 5312.3 5354.5 5356.7 5360.9 5400.2 6274.5 6325.0 6329.6 6331.1 6358.2 6161.5 6244.8 6284.2 6299.1 6315.5 6197.8 6283.1 6315.5 6322.9 6344.2 6142.7 6207.0 6218.5 6234.0 6235.9 6128.2 6162.1 6263.0 6301.9 6310.5 6052.4 6093.5 6094.3 6098.5 6103.2 6065.0 6105.5 6106.5 6108.2 6110.3 5890.0 5933.3 5994.1 6044.4 6051.8 5345.8 6080.2 6105.5 6109.5 6124.1 5854.1 5891.1 5897.4 5914.6 5953.3 05:07:13 INFO - PROCESS | 2079 | 05:07:13 INFO - PROCESS | 2079 | [#1] ext.html Cycles:180 Average:61386.39 Median:39096.40 stddev:125678.17 (321.5%) stddev-sans-first:125947.47 05:07:13 INFO - PROCESS | 2079 | Values: 455.3 476.7 575.8 607.7 612.4 28918.1 29233.4 36445.3 37274.4 37304.3 39502.8 39781.6 39787.2 39798.0 39811.8 9357.3 9588.0 9593.7 9604.5 9608.1 8376.6 9513.0 9636.9 9637.0 9654.7 9510.6 9766.7 9767.6 9767.9 9771.9 9685.3 9743.9 9746.8 9761.4 9763.2 7957.5 9574.4 9583.8 9585.4 9585.6 38002.2 38420.0 38438.0 38488.4 38489.0 3157.0 3197.9 3200.2 3202.9 3210.1 27391.7 27558.7 27685.3 28179.0 28392.4 2615.7 2647.8 2650.2 2803.9 2812.5 481671.0 482652.0 483387.0 483491.0 483884.0 51129.5 51968.2 58646.1 59107.7 59390.1 49132.0 63062.0 63853.4 63914.0 63942.0 13159.6 15664.5 15885.9 15887.5 15896.6 11357.2 11503.1 12167.8 12306.3 12309.9 15396.2 18481.6 18570.4 18596.2 18654.4 7474.5 7547.7 7693.0 7717.3 7896.9 920.3 949.7 971.7 1053.6 1092.6 24249.8 28041.7 28379.3 28722.9 28853.0 1062.4 1115.3 1159.5 1166.1 1176.2 14397.8 14459.3 14506.1 14808.7 14884.9 501428.0 503496.0 505035.5 505514.0 505761.9 54638.5 55077.4 55107.4 55141.9 55356.7 1402.9 1412.5 1430.3 1432.0 1437.8 23403.6 26432.6 29794.3 30358.2 30648.7 51220.5 51677.0 51710.2 51882.2 51962.7 425757.0 428882.0 429003.0 429854.0 430222.0 29456.9 29823.9 30228.7 30497.7 30676.2 42768.9 42880.0 43395.8 43879.3 43882.6 34686.5 36596.6 36603.6 36915.3 39897.4 37144.1 37150.3 37480.3 37909.4 38063.4 32951.5 39402.6 39718.2 39774.6 39789.0 36827.0 37306.6 37433.6 39018.0 39174.8 23148.5 24791.4 26202.7 26329.9 26627.0 05:07:13 INFO - PROCESS | 2079 | 05:07:13 INFO - PROCESS | 2079 | [#2] jquery.html Cycles:180 Average:32562.63 Median:24574.86 stddev:100636.05 (409.5%) stddev-sans-first:100889.22 05:07:13 INFO - PROCESS | 2079 | Values: 310.4 344.7 355.7 361.6 364.9 14463.1 14527.6 15292.8 19178.2 19313.9 18437.6 18571.4 18636.9 19267.8 19552.4 4088.7 5503.3 5579.3 5718.1 5845.1 4272.5 4316.2 6099.7 6375.2 6404.0 6062.0 6332.7 6361.2 6378.1 6386.8 6179.7 6324.1 6377.1 6380.7 6388.5 5531.5 5541.3 5543.7 5548.7 5552.4 18941.6 23899.4 24283.6 24407.0 24528.9 2127.4 2231.9 2563.3 2584.1 2883.4 14475.1 14592.9 14690.9 14818.3 15001.2 1630.6 1700.3 1702.0 1730.4 1748.0 94071.6 96297.0 96731.0 98540.0 99532.5 9544.8 9659.7 10162.2 10371.5 10556.6 12594.6 12689.6 12702.6 12730.6 12730.9 8412.6 8424.2 8446.8 8516.5 8527.1 8641.0 8647.7 8656.6 8735.3 8746.5 5602.6 5853.7 5865.3 6008.8 6126.9 1418.6 1754.7 1771.5 1793.1 1814.4 855.8 903.3 914.3 917.9 1038.7 16043.5 16652.6 19404.6 19408.9 20298.4 921.4 955.1 986.9 988.8 1035.7 10005.3 10917.5 10978.8 10983.6 10991.7 520827.0 600623.0 602920.0 658863.0 666240.0 59006.7 80723.6 86089.0 87224.0 89057.6 1124.6 1140.5 1159.7 1165.2 1165.4 18410.4 18771.3 18871.0 19068.6 19069.0 44833.6 47568.5 48163.5 48308.5 49120.0 64570.4 65102.0 65122.7 65850.7 66434.0 6338.9 7219.7 7507.9 7570.3 7570.5 11321.6 11366.3 11688.7 12010.8 12106.0 8445.9 9902.3 9933.6 10078.0 10105.2 9746.9 9958.9 10002.7 10122.4 10217.9 9051.5 10333.4 10447.4 10519.7 10614.7 7519.3 7644.5 7660.2 7662.2 7668.2 8324.8 10207.6 10378.9 10432.8 10508.4 05:07:13 INFO - PROCESS | 2079 | 05:07:13 INFO - PROCESS | 2079 | [#3] mootools.html Cycles:180 Average:6043.23 Median:5034.96 stddev:3187.26 (63.3%) stddev-sans-first:3176.76 05:07:13 INFO - PROCESS | 2079 | Values: 1360.6 1399.1 1573.2 1757.8 1944.6 8055.4 8272.9 8621.1 8775.5 8803.6 8569.2 8617.8 8738.5 8750.4 8789.5 2170.2 2234.5 2458.9 2483.3 2486.5 2083.7 2271.1 2509.2 2509.9 2511.0 2397.4 2450.6 2454.7 2460.6 2474.2 2411.7 2445.8 2458.9 2459.4 2472.4 2060.6 2335.6 2446.2 2455.3 2457.4 8878.7 8937.7 8940.5 8958.1 8995.1 5342.4 5430.0 5430.5 5452.2 5478.3 2940.0 3583.7 3737.0 3737.1 3759.0 1030.9 1051.3 1059.3 1081.4 1082.0 12125.9 12187.1 12200.3 12220.7 12241.9 4947.2 4951.2 4960.0 5007.1 5021.7 10197.3 10206.0 10234.5 10235.7 10239.5 5520.5 5520.8 5526.4 5528.4 5532.8 4973.9 5022.2 5028.8 5041.1 5044.0 3652.8 3705.0 3709.0 3903.2 3985.1 1285.2 1305.3 1322.0 1322.0 1323.2 1300.7 1330.4 1343.4 1358.5 1362.9 7993.8 8045.3 8168.4 8396.9 8403.3 1156.1 1157.4 1172.4 1177.2 1194.2 6186.0 6251.3 6255.8 6259.7 6270.1 10062.6 10600.3 10616.7 10728.2 10748.6 9521.7 10657.9 10701.6 10787.0 10811.1 8206.9 8315.9 8340.5 8350.5 8369.3 6741.9 7813.0 7845.7 7908.3 7944.4 9503.9 9525.5 9591.8 9732.6 9816.6 7966.9 10070.7 10093.9 10099.8 10111.8 7036.6 7060.8 7149.7 7154.9 7166.5 7318.9 7502.5 8165.3 8264.8 8287.1 6384.5 6965.3 7183.4 7737.1 7911.7 6974.4 7055.8 7172.7 7560.7 7788.0 7303.2 7723.3 7812.0 7812.2 7818.3 7053.0 7105.8 7128.7 7139.1 7180.0 6447.7 7073.1 7265.1 7291.6 7295.9 05:07:13 INFO - PROCESS | 2079 | 05:07:13 INFO - PROCESS | 2079 | [#4] prototype.html Cycles:180 Average:5725.21 Median:6617.09 stddev:1854.89 (28.0%) stddev-sans-first:1814.99 05:07:13 INFO - PROCESS | 2079 | Values: 308.8 316.6 329.9 330.5 331.7 6456.9 6615.9 6639.8 6645.7 6653.6 6562.2 6682.4 6702.9 6711.6 6721.2 4367.6 4436.3 4776.5 4850.9 4879.8 4848.7 4876.2 4919.0 4924.8 4934.9 4586.8 4639.1 4705.5 4716.5 4797.1 3838.4 4756.5 4764.4 4767.7 4775.4 4068.3 4070.0 4072.8 4074.0 4085.6 6329.5 6333.7 6335.8 6351.4 6370.9 5384.9 5568.4 5595.6 5611.3 5624.4 6942.8 6949.3 6950.4 6951.6 6952.4 4273.6 4362.8 4522.7 4533.2 4543.0 5463.9 7184.0 7308.8 7342.9 7435.5 3980.5 4579.7 4583.4 4584.4 4589.4 4643.1 4649.8 4651.1 4653.3 4674.8 5363.1 5519.6 5596.1 5617.7 5617.8 7043.9 7047.3 7048.8 7059.0 7064.1 1603.1 1626.5 1627.3 1627.8 1628.7 862.5 1038.6 1046.5 1052.0 1061.2 5660.0 6613.7 6633.8 6644.5 6652.4 7302.1 7323.7 7324.0 7332.8 7336.7 6395.3 6591.8 6596.4 6611.1 6618.3 3826.7 3875.4 3875.7 3878.8 3885.9 7611.0 7626.6 7629.6 7633.6 7640.8 7231.9 7519.6 7522.4 7534.7 7543.8 7616.7 7618.7 7619.9 7630.8 7642.1 7300.9 7301.5 7304.9 7305.0 7306.2 6924.7 7143.6 7163.3 7165.9 7172.8 7600.6 7620.5 7621.4 7621.4 7626.3 4623.9 4633.5 4634.1 4635.5 4650.7 5638.4 7230.4 7275.7 7278.4 7279.5 7009.4 7011.8 7015.9 7017.9 7027.7 6824.3 6942.9 7008.3 7028.3 7046.8 6824.3 6860.8 6861.5 6862.6 6864.8 5741.5 7032.1 7050.4 7055.0 7060.3 6647.7 6888.3 6888.7 6897.2 6903.1 05:07:13 INFO - PROCESS | 2079 | 05:07:13 INFO - PROCESS | 2079 | [#5] yui.html Cycles:180 Average:18355.27 Median:621.89 stddev:25808.19 (4150.0%) stddev-sans-first:25846.41 05:07:13 INFO - PROCESS | 2079 | Values: 665.2 716.8 724.0 746.0 769.0 14581.1 14853.6 14866.0 14868.8 14872.0 13426.4 14806.5 15098.9 15435.3 15462.4 681.9 692.0 841.7 868.7 869.2 688.1 841.9 866.7 869.2 869.3 897.7 913.8 914.5 914.5 915.0 711.3 722.1 723.5 898.5 900.9 530.9 532.3 656.8 668.6 669.0 14788.7 14824.7 14833.8 14854.9 14873.3 10401.3 12692.6 12729.7 12830.2 13102.9 9990.3 10055.0 10121.3 10130.4 10154.3 3531.8 3557.9 3560.4 3561.1 3563.9 76006.2 78134.6 80071.0 82220.3 83042.2 9612.6 9615.6 9657.7 9686.6 9976.0 16852.4 21407.6 21430.7 21449.6 21480.4 7624.2 7651.5 7768.6 7908.0 7947.6 4188.4 4203.6 4228.5 4284.8 4432.4 3641.0 3680.4 3684.4 3690.0 3695.1 646.8 654.0 655.4 672.6 674.4 543.9 607.6 621.7 627.4 628.6 8388.2 8519.7 8605.4 8647.1 8729.4 590.6 615.2 617.7 622.1 632.3 3936.7 3976.5 3985.5 4039.8 4082.3 92344.0 93339.6 94020.1 94106.5 95234.0 79019.8 79580.6 80131.0 81154.6 82047.6 68693.7 69372.6 69700.0 70167.0 70253.0 7899.1 7928.9 8038.8 8121.1 8157.6 49525.3 49742.5 49873.0 50961.0 51014.7 64052.0 66305.7 66536.3 66833.7 67749.3 10890.8 10957.9 10967.4 11140.1 11473.8 11888.4 11973.4 11980.5 11993.0 12322.9 10162.8 11174.2 11597.9 11670.9 11706.4 11446.4 11600.2 11650.2 11695.6 11838.2 11249.8 11355.7 11719.2 11739.4 11960.3 8642.7 9042.3 9375.9 9550.4 9648.2 9901.1 10068.2 10082.5 10096.2 10181.1 05:07:13 INFO - PROCESS | 2079 | -------- Summary: end -------- 05:07:13 INFO - PROCESS | 2079 | 05:07:14 INFO - TEST-INFO | 2079: exit 0 05:07:14 INFO - TEST-OK | dromaeo_css | took 1113771ms 05:07:14 INFO - TEST-START | kraken 05:07:14 INFO - operating with platform_type : mac_ 05:07:14 INFO - Initialising browser for kraken test... 05:07:14 INFO - TEST-INFO | started process 2083 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpSOfnpc/profile http://localhost:51403/getInfo.html) 05:07:14 INFO - PROCESS | 2083 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 05:07:16 INFO - PROCESS | 2083 | __metrics Screen width/height:1600/1200 05:07:16 INFO - PROCESS | 2083 | colorDepth:24 05:07:16 INFO - PROCESS | 2083 | Browser inner width/height: 1024/689 05:07:16 INFO - PROCESS | 2083 | __metrics 05:07:16 INFO - PROCESS | 2083 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:07:16 INFO - PROCESS | 2083 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:07:16 INFO - PROCESS | 2083 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:07:17 INFO - TEST-INFO | 2083: exit 0 05:07:17 INFO - Browser initialized. 05:07:17 INFO - Running cycle 1/1 for kraken test... 05:07:17 INFO - TEST-INFO | started process 2085 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpSOfnpc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:29 INFO - PROCESS | 2085 | RSS: Main: 241487872 05:07:29 INFO - PROCESS | 2085 | 05:07:31 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51403/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:32 INFO - PROCESS | 2085 | RSS: Main: 289984512 05:07:32 INFO - PROCESS | 2085 | 05:07:35 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51403/tests/kraken/driver.html?testName=audio-dft) 05:07:35 INFO - PROCESS | 2085 | RSS: Main: 300654592 05:07:35 INFO - PROCESS | 2085 | 05:07:38 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51403/tests/kraken/driver.html?testName=audio-fft) 05:07:39 INFO - PROCESS | 2085 | RSS: Main: 287150080 05:07:39 INFO - PROCESS | 2085 | 05:07:41 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51403/tests/kraken/driver.html?testName=audio-oscillator) 05:07:41 INFO - PROCESS | 2085 | RSS: Main: 288763904 05:07:41 INFO - PROCESS | 2085 | 05:07:44 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51403/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:44 INFO - PROCESS | 2085 | RSS: Main: 289886208 05:07:44 INFO - PROCESS | 2085 | 05:07:47 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51403/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:47 INFO - PROCESS | 2085 | RSS: Main: 289800192 05:07:47 INFO - PROCESS | 2085 | 05:07:50 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51403/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:51 INFO - PROCESS | 2085 | RSS: Main: 291115008 05:07:51 INFO - PROCESS | 2085 | 05:07:54 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51403/tests/kraken/driver.html?testName=json-parse-financial) 05:07:54 INFO - PROCESS | 2085 | RSS: Main: 290459648 05:07:54 INFO - PROCESS | 2085 | 05:07:56 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51403/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:56 INFO - PROCESS | 2085 | RSS: Main: 324554752 05:07:56 INFO - PROCESS | 2085 | 05:07:58 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51403/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:59 INFO - PROCESS | 2085 | RSS: Main: 326008832 05:07:59 INFO - PROCESS | 2085 | 05:08:01 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51403/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:08:01 INFO - PROCESS | 2085 | RSS: Main: 330788864 05:08:01 INFO - PROCESS | 2085 | 05:08:04 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:08:04 INFO - PROCESS | 2085 | RSS: Main: 332697600 05:08:04 INFO - PROCESS | 2085 | 05:08:07 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:08:08 INFO - PROCESS | 2085 | RSS: Main: 334532608 05:08:08 INFO - PROCESS | 2085 | 05:08:09 INFO - PROCESS | 2085 | Cycle 1(1): loaded http://localhost:51403/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51403/tests/kraken/driver.html?testName=ai-astar) 05:08:10 INFO - PROCESS | 2085 | RSS: Main: 394469376 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | __start_tp_report 05:08:10 INFO - PROCESS | 2085 | _x_x_mozilla_page_load 05:08:10 INFO - PROCESS | 2085 | _x_x_mozilla_page_load_details 05:08:10 INFO - PROCESS | 2085 | |i|pagename|runs| 05:08:10 INFO - PROCESS | 2085 | |0;ai-astar;111;109;111;110;113;111;115;113;112;112 05:08:10 INFO - PROCESS | 2085 | |1;audio-beat-detection;152;120;142;157;122;138;118;150;140;117 05:08:10 INFO - PROCESS | 2085 | |2;audio-dft;187;183;184;186;185;186;185;186;185;181 05:08:10 INFO - PROCESS | 2085 | |3;audio-fft;78;70;82;76;57;56;59;75;75;55 05:08:10 INFO - PROCESS | 2085 | |4;audio-oscillator;93;91;123;80;80;81;105;76;77;109 05:08:10 INFO - PROCESS | 2085 | |5;imaging-gaussian-blur;87;92;87;92;89;90;85;92;87;92 05:08:10 INFO - PROCESS | 2085 | |6;imaging-darkroom;95;95;95;95;97;94;96;94;95;96 05:08:10 INFO - PROCESS | 2085 | |7;imaging-desaturate;86;84;86;85;85;96;122;86;86;86 05:08:10 INFO - PROCESS | 2085 | |8;json-parse-financial;53;54;53;53;54;52;52;63;51;52 05:08:10 INFO - PROCESS | 2085 | |9;json-stringify-tinderbox;63;66;79;74;71;70;67;66;70;93 05:08:10 INFO - PROCESS | 2085 | |10;stanford-crypto-aes;83;82;83;82;81;80;82;81;83;80 05:08:10 INFO - PROCESS | 2085 | |11;stanford-crypto-ccm;128;134;125;118;127;131;123;124;123;122 05:08:10 INFO - PROCESS | 2085 | |12;stanford-crypto-pbkdf2;135;138;138;138;140;126;136;139;165;169 05:08:10 INFO - PROCESS | 2085 | |13;stanford-crypto-sha256-iterative;65;68;66;65;66;66;67;65;67;66 05:08:10 INFO - PROCESS | 2085 | __end_tp_report 05:08:10 INFO - PROCESS | 2085 | __start_cc_report 05:08:10 INFO - PROCESS | 2085 | _x_x_mozilla_cycle_collect,1140 05:08:10 INFO - PROCESS | 2085 | __end_cc_report 05:08:10 INFO - PROCESS | 2085 | __startTimestamp1454764090038__endTimestamp 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | ------- Summary: start ------- 05:08:10 INFO - PROCESS | 2085 | Number of tests: 14 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.70 (1.5%) stddev-sans-first:1.79 05:08:10 INFO - PROCESS | 2085 | Values: 111.0 109.0 111.0 110.0 113.0 111.0 115.0 113.0 112.0 112.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:141.00 stddev:15.22 (10.8%) stddev-sans-first:14.94 05:08:10 INFO - PROCESS | 2085 | Values: 152.0 120.0 142.0 157.0 122.0 138.0 118.0 150.0 140.0 117.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.50 stddev:1.75 (0.9%) stddev-sans-first:1.67 05:08:10 INFO - PROCESS | 2085 | Values: 187.0 183.0 184.0 186.0 185.0 186.0 185.0 186.0 185.0 181.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#3] audio-fft Cycles:10 Average:68.30 Median:75.00 stddev:10.41 (13.9%) stddev-sans-first:10.44 05:08:10 INFO - PROCESS | 2085 | Values: 78.0 70.0 82.0 76.0 57.0 56.0 59.0 75.0 75.0 55.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:80.00 stddev:16.02 (20.0%) stddev-sans-first:16.98 05:08:10 INFO - PROCESS | 2085 | Values: 93.0 91.0 123.0 80.0 80.0 81.0 105.0 76.0 77.0 109.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:91.00 stddev:2.67 (2.9%) stddev-sans-first:2.70 05:08:10 INFO - PROCESS | 2085 | Values: 87.0 92.0 87.0 92.0 89.0 90.0 85.0 92.0 87.0 92.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.00 stddev:0.92 (1.0%) stddev-sans-first:0.97 05:08:10 INFO - PROCESS | 2085 | Values: 95.0 95.0 95.0 95.0 97.0 94.0 96.0 94.0 95.0 96.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#7] imaging-desaturate Cycles:10 Average:90.20 Median:86.00 stddev:11.67 (13.6%) stddev-sans-first:12.28 05:08:10 INFO - PROCESS | 2085 | Values: 86.0 84.0 86.0 85.0 85.0 96.0 122.0 86.0 86.0 86.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:53.00 stddev:3.40 (6.4%) stddev-sans-first:3.60 05:08:10 INFO - PROCESS | 2085 | Values: 53.0 54.0 53.0 53.0 54.0 52.0 52.0 63.0 51.0 52.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:70.50 stddev:8.70 (12.3%) stddev-sans-first:8.61 05:08:10 INFO - PROCESS | 2085 | Values: 63.0 66.0 79.0 74.0 71.0 70.0 67.0 66.0 70.0 93.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.00 stddev:1.16 (1.4%) stddev-sans-first:1.13 05:08:10 INFO - PROCESS | 2085 | Values: 83.0 82.0 83.0 82.0 81.0 80.0 82.0 81.0 83.0 80.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#11] stanford-crypto-ccm Cycles:10 Average:125.50 Median:126.00 stddev:4.65 (3.7%) stddev-sans-first:4.84 05:08:10 INFO - PROCESS | 2085 | Values: 128.0 134.0 125.0 118.0 127.0 131.0 123.0 124.0 123.0 122.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.40 Median:138.50 stddev:13.57 (9.8%) stddev-sans-first:14.13 05:08:10 INFO - PROCESS | 2085 | Values: 135.0 138.0 138.0 138.0 140.0 126.0 136.0 139.0 165.0 169.0 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - PROCESS | 2085 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:0.97 05:08:10 INFO - PROCESS | 2085 | Values: 65.0 68.0 66.0 65.0 66.0 66.0 67.0 65.0 67.0 66.0 05:08:10 INFO - PROCESS | 2085 | -------- Summary: end -------- 05:08:10 INFO - PROCESS | 2085 | 05:08:10 INFO - TEST-INFO | 2085: exit 0 05:08:10 INFO - TEST-OK | kraken | took 56418ms 05:08:10 INFO - SUITE-END | took 1170s 05:08:11 INFO - Completed test suite (00:19:30) 05:08:11 INFO - Working with test: dromaeo_css 05:08:11 INFO - Generating results file: dromaeo_css 05:08:11 INFO - Working with test: kraken 05:08:11 INFO - Generating results file: kraken 05:08:11 INFO - javascript benchmark 05:08:11 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:08:11 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 05:08:11 INFO - process_Request line: dromaeo_css 7456.82 graph.html#tests=[[72,64,59]] 05:08:11 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:08:11 INFO - process_Request line: kraken 1407.90 graph.html#tests=[[232,64,59]] 05:08:11 INFO - RETURN: dromaeo_css: 7456.82 05:08:11 INFO - RETURN: kraken: 1407.90 05:08:11 INFO - javascript benchmark 05:08:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3418.8461519231632, 3444.7933124012575, 3490.319387719395, 3499.0, 3531.876384326549, 5602.355729091146, 5644.6330988485715, 5692.800751973677, 5698.230738850254, 5721.9999999999945, 5589.133684278943, 5693.146028095788, 5755.453231942969, 5870.530357571395, 5879.11813228016, 5151.253068305094, 5162.096633089213, 5186.818461353853, 5190.740462976855, 5221.843344699665, 4767.904641907177, 5285.339332583427, 5314.0, 5320.76056577455, 5332.600054995872, 4285.721428107194, 5127.359080114986, 5178.896422071575, 5254.894902101936, 5265.104932161523, 5118.053160165344, 5140.0, 5185.092608793446, 5191.844244672666, 5220.738963051832, 5117.795288188468, 5162.586993040585, 5186.662866913639, 5192.299039629639, 5236.581073514109, 5520.806771763009, 5613.298337707786, 5634.577406694514, 5649.661020338793, 5656.462636049569, 6161.137440758297, 6191.442770150663, 6197.783077592263, 6198.659073750942, 6204.534659900525, 4631.62946964242, 6292.1820163378925, 6303.0, 6307.621542707407, 6331.14529538511, 5789.189513468118, 5800.245968024172, 5802.593818432669, 5828.242328497268, 5837.328707198684, 6199.132121502993, 6211.844703882349, 6280.371962803774, 6308.589941653778, 6313.842153946188, 6103.176071230416, 6106.885399019288, 6160.168377269011, 6182.165407669997, 6183.412575805291, 6250.937490625036, 6265.373462653698, 6272.247330320391, 6281.309056003836, 6293.118963345135, 6109.450149486567, 6136.355682653347, 6156.753729850845, 6171.259448866164, 6188.350223226496, 5172.0, 6111.083337499411, 6213.409726076015, 6229.937700623026, 6250.562460627803, 4828.903421931612, 4844.563989240985, 4853.2720091985775, 4875.8049678012485, 4902.2646603009125, 4285.250081235771, 4361.629261512743, 4370.104128653618, 4443.4445694288215, 4447.910261985834, 5754.223179870748, 5783.971080144573, 5789.218455508537, 5796.942030579725, 5831.096180092092, 5840.737166827503, 6001.009833377444, 6244.188255526843, 6248.9062664059165, 6294.339094395114, 5547.5007249347755, 5685.175649530877, 5685.630434021776, 5728.341240757283, 5756.5682573807135, 5312.309399778082, 5354.518093371616, 5356.705381204071, 5360.865978350573, 5400.243965844785, 6274.498040156776, 6325.0, 6329.588576742497, 6331.081993110973, 6358.23701155855, 6161.476274516537, 6244.812655620339, 6284.24589049317, 6299.055141728777, 6315.463185629273, 6197.845053873689, 6283.057541368831, 6315.5263355247625, 6322.936770632235, 6344.175257216532, 6142.662153581596, 6207.0, 6218.502519798315, 6234.0, 6235.937640623536, 6128.167033931239, 6162.075688646739, 6262.968685156728, 6301.905471417841, 6310.463610592968, 6052.364501727256, 6093.451589356979, 6094.299155596986, 6098.481629061579, 6103.176071230327, 6065.0, 6105.48103411215, 6106.542009349406, 6108.17539632144, 6110.327863935053, 5890.0, 5933.317668468008, 5994.13085102649, 6044.395560443921, 6051.818445446467, 5345.839624811262, 6080.209572755691, 6105.45050945417, 6109.511239100772, 6124.127207212446, 5854.121881717776, 5891.086881533369, 5897.3807750185615, 5914.556408269311, 5953.345132035386], "unit": "score", "name": "dojo.html", "value": 5676.342669802261}, {"lowerIsBetter": false, "replicates": [455.3465776610562, 476.68062398193206, 575.7610591604482, 607.6809674920669, 612.4212619074974, 28918.132456026327, 29233.41533169335, 36445.27109457813, 37274.44088338677, 37304.25391492168, 39502.80248598753, 39781.60218397815, 39787.20425591486, 39798.0, 39811.80094099533, 9357.34498585101, 9588.0, 9593.66422175224, 9604.51977401127, 9608.087231712976, 8376.623051962677, 9513.048695130467, 9636.855447168298, 9637.0, 9654.662086826962, 9510.619575216982, 9766.658166964122, 9767.609295628203, 9767.853482197772, 9771.853422198672, 9685.32202745808, 9743.853842192337, 9746.756331091708, 9761.365511241818, 9763.170130538912, 7957.482763620346, 9574.377665451793, 9583.808323833484, 9585.424874507551, 9585.568649410794, 38002.23995520074, 38420.0, 38438.0, 38488.422673659916, 38489.03777405559, 3156.9684303157132, 3197.936041279185, 3200.199950012497, 3202.9199270018203, 3210.101171671912, 27391.72608273912, 27558.724412756015, 27685.30786730348, 28179.013734519398, 28392.432151356858, 2615.699194592627, 2647.8014148938714, 2650.1519513755607, 2803.929901752452, 2812.4937511247967, 481671.0, 482652.0, 483387.0, 483491.0, 483884.0, 51129.48870511322, 51968.22047669288, 58646.12030819541, 59107.70446147742, 59390.10914836281, 49132.0, 63062.0, 63853.36146638567, 63914.0, 63942.0, 13159.60521184366, 15664.530064098095, 15885.920570397075, 15887.52337429879, 15896.602584935468, 11357.204995650225, 11503.07975361969, 12167.817482737766, 12306.323152226714, 12309.876901231051, 15396.23018849053, 18481.630367392576, 18570.44288671342, 18596.163172657263, 18654.440366788745, 7474.476786624884, 7547.73582924595, 7693.0, 7717.262928134734, 7896.933913921662, 920.2959735802117, 949.7245798718433, 971.7279161834698, 1053.562771449855, 1092.5738961804902, 24249.75750242475, 28041.71958280432, 28379.290517737223, 28722.856385718354, 28853.0, 1062.389126252408, 1115.2806439846254, 1159.478234794346, 1166.1370585766472, 1176.1943068997764, 14397.78403323951, 14459.349329280207, 14506.1296322221, 14808.703825923421, 14884.925575372054, 501428.0, 503496.0, 505035.4748226309, 505514.0, 505761.94238057884, 54638.45361546334, 55077.44922550723, 55107.44892551103, 55141.897162056535, 55356.72321638366, 1402.9088109272864, 1412.498563010137, 1430.3134495442246, 1432.0405328429977, 1437.8202724659418, 23403.648945265493, 26432.603510946967, 29794.255143621584, 30358.241043973194, 30648.693513065475, 51220.48779512306, 51677.0, 51710.22434663558, 51882.22176667429, 51962.700932475476, 425757.0, 428882.0, 429003.0, 429854.0, 430222.0, 29456.852715736284, 29823.850880745456, 30228.697713023466, 30497.69502305037, 30676.233094172825, 42768.93077673083, 42880.0, 43395.783021084695, 43879.34180987224, 43882.56117438785, 34686.47970280498, 36596.63403365932, 36603.63396366109, 36915.261694766494, 39897.40153897636, 37144.07139821526, 37150.256994860494, 37480.250394992494, 37909.43135853019, 38063.42904856374, 32951.50572741363, 39402.60597393989, 39718.2056358877, 39774.60225397709, 39789.0, 36827.0, 37306.62693373032, 37433.625663744104, 39018.0, 39174.804125979186, 23148.537029259656, 24791.380215494035, 26202.73797262003, 26329.86835065889, 26627.0], "unit": "score", "name": "ext.html", "value": 19227.25770654778}, {"lowerIsBetter": false, "replicates": [310.408671480829, 344.68805730813625, 355.6674509333773, 361.6239111324223, 364.8613526859791, 14463.059901106433, 14527.636809079779, 15292.84707152928, 19178.23287068519, 19313.903430482827, 18437.631247375044, 18571.350002749907, 18636.906815465936, 19267.807321926775, 19552.41342759716, 4088.6729061674996, 5503.257060296869, 5579.330480342354, 5718.056520674084, 5845.09401042839, 4272.530021697627, 4316.158349121923, 6099.725512351954, 6375.171227740388, 6404.039394090901, 6062.0, 6332.715027823759, 6361.2048493938255, 6378.075179099028, 6386.840328991789, 6179.66011869347, 6324.146240257553, 6377.075324078006, 6380.712867920908, 6388.456981156584, 5531.502164805147, 5541.33503979525, 5543.667379957175, 5548.72256387179, 5552.444755524415, 18941.621167576708, 23899.40251493709, 24283.63574546383, 24407.0, 24528.896199671057, 2127.383058912929, 2231.8995645196005, 2563.3207200091992, 2584.1213987244428, 2883.35124596964, 14475.131492110508, 14592.90553208503, 14690.853091469055, 14818.259087045604, 15001.174935378549, 1630.5678995066316, 1700.2688843797162, 1702.0383483331877, 1730.4203091964207, 1747.9737803932824, 94071.58892616617, 96297.03702963021, 96731.0, 98540.02919941633, 99532.50233748858, 9544.761380965392, 9659.71020869375, 10162.237832162617, 10371.533281002374, 10556.63051793199, 12594.622161335175, 12689.555865544662, 12702.555410560586, 12730.554430594884, 12730.872691273154, 8412.62143203545, 8424.199701028392, 8446.83106337882, 8516.531590762444, 8527.10465401124, 8640.956795216109, 8647.654093836176, 8656.61045252965, 8735.301175905912, 8746.475211487332, 5602.579806514446, 5853.678047707332, 5865.2961644602065, 6008.8197354078575, 6126.908096378558, 1418.638247246966, 1754.736789481588, 1771.4508502364308, 1793.0944872839393, 1814.4375243674504, 855.8288342331558, 903.263839970422, 914.3142643017736, 917.9173874351341, 1038.7039693687263, 16043.518694439186, 16652.58368540772, 19404.611907761766, 19408.902955485133, 20298.391048268575, 921.3918813583138, 955.1499165742495, 986.8717066781272, 988.8121256961155, 1035.7100011996654, 10005.299629025898, 10917.454127293604, 10978.835317470244, 10983.560657573607, 10991.670249892515, 520827.0, 600623.0, 602920.0, 658863.0, 666240.0, 59006.70496647575, 80723.59638201771, 86089.0, 87224.0, 89057.55471222602, 1124.5951457475303, 1140.4867809485766, 1159.6752909185611, 1165.2367699157066, 1165.3648761425043, 18410.35563755262, 18771.34300299483, 18871.0, 19068.618627627093, 19069.0, 44833.55166448294, 47568.52431475779, 48163.5183648159, 48308.516914830405, 49120.0, 64570.35429645643, 65102.0, 65122.69754604976, 65850.67074664788, 66434.0, 6338.936610633835, 7219.711211551479, 7507.948887155693, 7570.280823321776, 7570.470067095251, 11321.603743868925, 11366.318020918772, 11688.70778230551, 12010.819837702616, 12106.0, 8445.873311900203, 9902.25733070037, 9933.552990115606, 10078.042585954323, 10105.24210684187, 9746.9025309746, 9958.850617240601, 10002.699919002442, 10122.44326562046, 10217.897821021694, 9051.547422628973, 10333.379997200193, 10447.410635310072, 10519.684409467729, 10614.695349452808, 7519.285667861544, 7644.541327520366, 7660.233976602295, 7662.195469475625, 7668.156502784577, 8324.833503329779, 10207.591696332063, 10378.896211037792, 10432.843507347548, 10508.42203678805], "unit": "score", "name": "jquery.html", "value": 9114.574748575551}, {"lowerIsBetter": false, "replicates": [1360.591822453264, 1399.1255465334166, 1573.2212554785378, 1757.7802774653169, 1944.6013567218722, 8055.355571554276, 8272.917270827289, 8621.051684314729, 8775.47347159171, 8803.64785408583, 8569.228769410753, 8617.784555386119, 8738.519381434018, 8750.431221970583, 8789.472631642106, 2170.175333373316, 2234.519578290667, 2458.91393801217, 2483.3170878008514, 2486.502699460106, 2083.687446882972, 2271.057511132878, 2509.1594315904194, 2509.8619575923317, 2510.962335564968, 2397.4485868250313, 2450.571150048743, 2454.7177074636465, 2460.6309053641917, 2474.158786012755, 2411.722651895037, 2445.8410203336816, 2458.913938012161, 2459.4220358215807, 2472.431340791628, 2060.5672808710187, 2335.637976113705, 2446.1805295226122, 2455.300239431753, 2457.3733697907096, 8878.73363799087, 8937.73186804397, 8940.508272045035, 8958.148975847284, 8995.100489951017, 5342.385625653061, 5430.049741295257, 5430.456954304577, 5452.2094296327205, 5478.315210598676, 2939.9853000735075, 3583.6595523425235, 3737.0470530014672, 3737.0657335666083, 3759.0226541099237, 1030.8814486334018, 1051.3166441812807, 1059.3220338983058, 1081.4376524207446, 1081.9613171355509, 12125.939370303093, 12187.085968552394, 12200.328981905992, 12220.694482637917, 12241.877581224251, 4947.208446648519, 4951.207806750903, 4960.0, 5007.098722230034, 5021.748912554358, 10197.286189966779, 10206.030427109412, 10234.48827558619, 10235.744106397251, 10239.48802559869, 5520.475554822286, 5520.751566179531, 5526.419725928719, 5528.44715528452, 5532.75102620375, 4973.850784476472, 5022.171341728647, 5028.7737051832755, 5041.1430056889685, 5044.0, 3652.7990960497427, 3705.0366904605, 3708.962910370916, 3903.2435404067714, 3985.103351745892, 1285.1582213650074, 1305.2951406240713, 1321.9867801322057, 1322.041519898066, 1323.1664051647592, 1300.681333073403, 1330.4279159961116, 1343.3619030960215, 1358.4701966233176, 1362.9114107583173, 7993.8401231976195, 8045.316148127357, 8168.42821002536, 8396.916030839613, 8403.285720713686, 1156.069364161841, 1157.4212893553224, 1172.3962159487871, 1177.2053863642006, 1194.2416565480983, 6186.041163619646, 6251.28110267316, 6255.812325630239, 6259.71831267594, 6270.059491076284, 10062.647807326855, 10600.257981941344, 10616.681499555025, 10728.249022568345, 10748.570057197781, 9521.714348569416, 10657.946710266398, 10701.625443109453, 10787.0, 10811.135109191246, 8206.876896846552, 8315.916840831514, 8340.541270230196, 8350.498970061815, 8369.3304535637, 6741.898871516833, 7813.0, 7845.725399610987, 7908.327792137733, 7944.364450843803, 9503.857442138511, 9525.52372381392, 9591.808163836544, 9732.562034708313, 9816.607335706492, 7966.880496792669, 10070.748231294276, 10093.949530252301, 10099.848502272618, 10111.848322275318, 7036.577805331696, 7060.7881763547175, 7149.749758758627, 7154.856902862018, 7166.498345116001, 7318.890216646861, 7502.549847009197, 8165.346772258086, 8264.793380165544, 8287.088420273645, 6384.457321127757, 6965.33829286217, 7183.389411900047, 7737.148913619385, 7911.723089691834, 6974.37230649244, 7055.835787095072, 7172.677229524579, 7560.735374261874, 7788.0, 7303.196648368781, 7723.34351580122, 7812.0, 7812.218778122142, 7818.296353328, 7053.047838541731, 7105.75129870452, 7128.6792094356915, 7139.071920650283, 7179.9641001796745, 6447.677616119269, 7073.080499535027, 7265.055542779405, 7291.562506249642, 7295.854082918206], "unit": "score", "name": "mootools.html", "value": 4933.6244557488035}, {"lowerIsBetter": false, "replicates": [308.82088388734536, 316.58369244443554, 329.89113592514474, 330.51084395095245, 331.7395844262254, 6456.870862582746, 6615.915986464261, 6639.834004149892, 6645.734170633181, 6653.602743423875, 6562.179727534059, 6682.398584127428, 6702.8659426811555, 6711.5973041617535, 6721.1598550181225, 4367.606915377615, 4436.334549817521, 4776.546228108333, 4850.927236091461, 4879.829205977791, 4848.7090774553635, 4876.220179634425, 4919.0, 4924.80300787968, 4934.851954441355, 4586.77066146693, 4639.072185562884, 4705.458872229686, 4716.50476699945, 4797.088553174885, 3838.366669499543, 4756.453007904066, 4764.356811830393, 4767.69010014348, 4775.42694876617, 4068.3083875741195, 4070.0, 4072.7963601819793, 4073.959260407388, 4085.611866872642, 6329.4619957303685, 6333.7466501339895, 6335.778247761306, 6351.396617321347, 6370.872582548323, 5384.865378365533, 5568.387477377485, 5595.608307418483, 5611.326640803089, 5624.3531993820825, 6942.826429339257, 6949.339812717783, 6950.374466298057, 6951.582905025714, 6952.374286314256, 4273.55127711592, 4362.847300344472, 4522.728636281834, 4533.206688829475, 4543.0, 5463.863403414906, 7184.0, 7308.780736577911, 7342.926570734277, 7435.4795164337975, 3980.4626375438943, 4579.656525760582, 4583.404157459576, 4584.404027476407, 4589.403377560896, 4643.142742902798, 4649.790759415834, 4651.069786042805, 4653.348531205634, 4674.789634466457, 5363.088274993261, 5519.641223320471, 5596.132599447093, 5617.691026993554, 5617.831465056055, 7043.92956070443, 7047.330503602149, 7048.823779405453, 7059.047028651066, 7064.116985376828, 1603.1182849432764, 1626.487656388234, 1627.2839950421776, 1627.788387509616, 1628.7149748793913, 862.4609618988133, 1038.6416686243233, 1046.549983507084, 1051.9631812886512, 1061.1881910338598, 5659.971700141473, 6613.669316534154, 6633.800985970429, 6644.501662375341, 6652.368025037614, 7302.123745150616, 7323.7436689715605, 7323.963380183065, 7332.816679582947, 7336.743213987592, 6395.328490508523, 6591.769288074895, 6596.439302659328, 6611.074449577063, 6618.271990081087, 3826.732128750961, 3875.3799392097694, 3875.7093218008763, 3878.8448462061765, 3885.86399476017, 7611.0, 7626.6186690665245, 7629.580373079534, 7633.580153091633, 7640.770776876702, 7231.877998765745, 7519.586422746692, 7522.398208143335, 7534.736284230025, 7543.811404714926, 7616.657250423744, 7618.695252189961, 7619.885701714478, 7630.8092297693, 7642.121156067013, 7300.926990730237, 7301.525400848928, 7304.8539029218055, 7305.0, 7306.159791624032, 6924.6537673117155, 7143.607101609461, 7163.31948464895, 7165.928340716526, 7172.784816455514, 7600.619969001417, 7620.542767433972, 7621.390288776774, 7621.428392870446, 7626.313631773167, 4623.86128416148, 4633.490316065163, 4634.119517291704, 4635.51327110662, 4650.651201159994, 5638.379778224474, 7230.42156627479, 7275.672594733144, 7278.417726581966, 7279.490435669452, 7009.369156775914, 7011.789646310619, 7015.859682806214, 7017.894731578928, 7027.718891244497, 6824.283450237654, 6942.895856562257, 7008.299170082951, 7028.332308430691, 7046.823829404101, 6824.317568243136, 6860.794176174722, 6861.451083913175, 6862.553933994274, 6864.759733409307, 5741.483266506035, 7032.12098487689, 7050.365467107985, 7055.0, 7060.3292687194635, 6647.734090636513, 6888.345607167311, 6888.655567221719, 6897.206821215625, 6903.1371078615175], "unit": "score", "name": "prototype.html", "value": 5100.589974570809}, {"lowerIsBetter": false, "replicates": [665.181826353585, 716.8136284566011, 723.9601821899795, 745.9701611935523, 768.9577073260975, 14581.125132492058, 14853.55439336819, 14866.033707809012, 14868.77696834547, 14872.033317834334, 13426.39581218845, 14806.481773137943, 15098.924505377458, 15435.305411256493, 15462.381504739797, 681.9045333653293, 691.9550229235109, 841.6633346661323, 868.695956415256, 869.1873098652749, 688.102026854954, 841.8947631546057, 866.7399780065987, 869.2090197919894, 869.3132425383939, 897.6723495923974, 913.7532866126141, 914.4513292024765, 914.5427286356821, 914.9542522873863, 711.2602892991283, 722.1406526233765, 723.4935545118385, 898.5148020069155, 900.8783814185099, 530.9097453432887, 532.3133158225884, 656.8226578823738, 668.6289109544201, 669.0198858672069, 14788.70422591553, 14824.703505929929, 14833.777493337608, 14854.925725371411, 14873.33070011852, 10401.323913945569, 12692.555760548428, 12729.681757956125, 12830.230186188859, 13102.934485327607, 9990.300678952477, 10054.962335075616, 10121.342112762646, 10130.442825644586, 10154.28919975602, 3531.7704349217474, 3557.928841423183, 3560.4481305397703, 3561.0741207286032, 3563.8574457021687, 76006.23993760103, 78134.60932695356, 80070.99822504484, 82220.30008649625, 83042.16957830405, 9612.567434465465, 9615.5672994714, 9657.661981830603, 9686.564104615309, 9975.950120249352, 16852.41016564439, 21407.57184856294, 21430.678539821914, 21449.571008580053, 21480.355589332346, 7624.19945905683, 7651.464397492122, 7768.572728499874, 7907.9604601976625, 7947.602619869099, 4188.392683060939, 4203.55862634419, 4228.534861165331, 4284.828606855754, 4432.357308190359, 3641.0, 3680.392735198716, 3684.392075307544, 3689.9815500922323, 3695.0577602711674, 646.8479907221799, 653.9509536784665, 655.3872129558878, 672.5762769455212, 674.3593586093231, 543.8939406815633, 607.6475644126396, 621.7142402403367, 627.4353082226033, 628.6448156791398, 8388.203120703523, 8519.659213631508, 8605.35459840502, 8647.135286471303, 8729.432586881832, 590.5541316306247, 615.2094558492316, 617.7405489694339, 622.0575827621158, 632.2634131237057, 3936.7047471439755, 3976.5029371328583, 3985.4818873546255, 4039.818208180639, 4082.3468245080644, 92344.0, 93339.59990600146, 94020.11959760904, 94106.5294673536, 95234.0, 79019.8147027795, 79580.6020969903, 80130.9869013094, 81154.59422702849, 82047.5897620508, 68693.65653171702, 69372.61254774877, 69699.99999999898, 70167.0, 70253.0, 7899.0521137463875, 7928.881066783887, 8038.799030024296, 8121.147279535564, 8157.592120393838, 49525.257121143935, 49742.50257497523, 49873.00253994827, 50961.0, 51014.7246318845, 64052.0, 66305.72802623663, 66536.33463665302, 66833.66583167053, 67749.32250677627, 10890.78218435611, 10957.890421096006, 10967.396793176453, 11140.10879129684, 11473.770524589294, 11888.405579720806, 11973.401329933295, 11980.532756230365, 11993.020724075119, 12322.938385308016, 10162.796744065225, 11174.217804753589, 11597.884021160018, 11670.88329116698, 11706.35615041181, 11446.427678616203, 11600.245984011011, 11650.184487085822, 11695.590654327398, 11838.171328006956, 11249.831252531056, 11355.716107097389, 11719.238249513754, 11739.354335511629, 11960.282383057045, 8642.740717778477, 9042.290234146927, 9375.85936210971, 9550.413798245507, 9648.179904708179, 9901.098354952364, 10068.194544436574, 10082.495875206358, 10096.19230461576, 10181.083702466814], "unit": "score", "name": "yui.html", "value": 6867.229805376133}], "name": "dromaeo_css", "value": 7456.826270906922}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 109.0, 111.0, 110.0, 113.0, 111.0, 115.0, 113.0, 112.0, 112.0], "unit": "score", "name": "ai-astar", "value": 111.7}, {"lowerIsBetter": true, "replicates": [152.0, 120.0, 142.0, 157.0, 122.0, 138.0, 118.0, 150.0, 140.0, 117.0], "unit": "score", "name": "audio-beat-detection", "value": 135.6}, {"lowerIsBetter": true, "replicates": [187.0, 183.0, 184.0, 186.0, 185.0, 186.0, 185.0, 186.0, 185.0, 181.0], "unit": "score", "name": "audio-dft", "value": 184.8}, {"lowerIsBetter": true, "replicates": [78.0, 70.0, 82.0, 76.0, 57.0, 56.0, 59.0, 75.0, 75.0, 55.0], "unit": "score", "name": "audio-fft", "value": 68.3}, {"lowerIsBetter": true, "replicates": [93.0, 91.0, 123.0, 80.0, 80.0, 81.0, 105.0, 76.0, 77.0, 109.0], "unit": "score", "name": "audio-oscillator", "value": 91.5}, {"lowerIsBetter": true, "replicates": [87.0, 92.0, 87.0, 92.0, 89.0, 90.0, 85.0, 92.0, 87.0, 92.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 89.3}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 97.0, 94.0, 96.0, 94.0, 95.0, 96.0], "unit": "score", "name": "imaging-darkroom", "value": 95.2}, {"lowerIsBetter": true, "replicates": [86.0, 84.0, 86.0, 85.0, 85.0, 96.0, 122.0, 86.0, 86.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 90.2}, {"lowerIsBetter": true, "replicates": [53.0, 54.0, 53.0, 53.0, 54.0, 52.0, 52.0, 63.0, 51.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 53.7}, {"lowerIsBetter": true, "replicates": [63.0, 66.0, 79.0, 74.0, 71.0, 70.0, 67.0, 66.0, 70.0, 93.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 71.9}, {"lowerIsBetter": true, "replicates": [83.0, 82.0, 83.0, 82.0, 81.0, 80.0, 82.0, 81.0, 83.0, 80.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.7}, {"lowerIsBetter": true, "replicates": [128.0, 134.0, 125.0, 118.0, 127.0, 131.0, 123.0, 124.0, 123.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.5}, {"lowerIsBetter": true, "replicates": [135.0, 138.0, 138.0, 138.0, 140.0, 126.0, 136.0, 139.0, 165.0, 169.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 142.4}, {"lowerIsBetter": true, "replicates": [65.0, 68.0, 66.0, 65.0, 66.0, 66.0, 67.0, 65.0, 67.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.1}], "name": "kraken", "value": 1407.9}]} 05:08:11 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1407.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7456.82"}}} 05:08:11 INFO - Return code: 0 05:08:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:08:11 INFO - # TBPL SUCCESS # 05:08:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:08:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:08:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:08:11 INFO - 2016-02-06 05:08:11.776 screenresolution[2089:17320] starting screenresolution argv=screenresolution get 05:08:11 INFO - 2016-02-06 05:08:11.779 screenresolution[2089:17320] Display 0: 1600x1200x32@60 05:08:11 INFO - 2016-02-06 05:08:11.785 screenresolution[2090:17323] starting screenresolution argv=screenresolution list 05:08:11 INFO - Available Modes on Display 0 05:08:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:08:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:08:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:08:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:08:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:08:12 INFO - Intel Iris: 05:08:12 INFO - Chipset Model: Intel Iris 05:08:12 INFO - Type: GPU 05:08:12 INFO - Bus: Built-In 05:08:12 INFO - VRAM (Dynamic, Max): 1536 MB 05:08:12 INFO - Vendor: Intel (0x8086) 05:08:12 INFO - Device ID: 0x0a2e 05:08:12 INFO - Revision ID: 0x0009 05:08:12 INFO - Displays: 05:08:12 INFO - Display: 05:08:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:08:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:08:12 INFO - Display Serial Number: 1600x1200 60 05:08:12 INFO - Main Display: Yes 05:08:12 INFO - Mirror: Off 05:08:12 INFO - Online: Yes 05:08:12 INFO - Rotation: Supported 05:08:12 INFO - Return code: 0 05:08:12 INFO - Running post-action listener: _resource_record_post_action 05:08:12 INFO - Running post-run listener: _resource_record_post_run 05:08:13 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 1110528; Write bytes: 707725824; Read time: 13; Write time: 2116 05:08:13 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388571648; Write bytes: 382907904; Read time: 29933; Write time: 717 05:08:13 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 1110528; Write bytes: 301536256; Read time: 13; Write time: 1348 05:08:13 INFO - Running post-run listener: _upload_blobber_files 05:08:13 INFO - Blob upload gear active. 05:08:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:08:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:08:13 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:08:13 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:08:13 INFO - (blobuploader) - INFO - Open directory for files ... 05:08:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:08:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:08:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:14 INFO - (blobuploader) - INFO - Done attempting. 05:08:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:08:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:08:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:15 INFO - (blobuploader) - INFO - Done attempting. 05:08:15 INFO - (blobuploader) - INFO - Iteration through files over. 05:08:15 INFO - Return code: 0 05:08:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:08:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:08:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32ffd0b2f15c5fc6efa7127e42cd97922e403a9ce140def88ce573306fe558a0519a51cf9dc69294f027e58f4a2af92c24cc0aac43114073e4ea5c1d670bb56e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a2e66dc972063a64df92c5bf771028a44a17b91141d9dfc60e8c3f1b6134ba0e1706890ecd774e9943f96833f79a3f08425d89b3737e935afc0233dee821f606"} 05:08:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:08:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:08:15 INFO - Contents: 05:08:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32ffd0b2f15c5fc6efa7127e42cd97922e403a9ce140def88ce573306fe558a0519a51cf9dc69294f027e58f4a2af92c24cc0aac43114073e4ea5c1d670bb56e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a2e66dc972063a64df92c5bf771028a44a17b91141d9dfc60e8c3f1b6134ba0e1706890ecd774e9943f96833f79a3f08425d89b3737e935afc0233dee821f606"} 05:08:15 INFO - Running post-run listener: copy_logs_to_upload_dir 05:08:15 INFO - Copying logs to upload dir... 05:08:15 INFO - mkdir: /builds/slave/test/build/upload/logs 05:08:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.221210 ========= master_lag: 26.99 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-02-06 05:08:42.702102) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-06 05:08:42.705795) ========= 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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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/Fx-Team/sha512/32ffd0b2f15c5fc6efa7127e42cd97922e403a9ce140def88ce573306fe558a0519a51cf9dc69294f027e58f4a2af92c24cc0aac43114073e4ea5c1d670bb56e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a2e66dc972063a64df92c5bf771028a44a17b91141d9dfc60e8c3f1b6134ba0e1706890ecd774e9943f96833f79a3f08425d89b3737e935afc0233dee821f606"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011527 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454512056/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32ffd0b2f15c5fc6efa7127e42cd97922e403a9ce140def88ce573306fe558a0519a51cf9dc69294f027e58f4a2af92c24cc0aac43114073e4ea5c1d670bb56e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a2e66dc972063a64df92c5bf771028a44a17b91141d9dfc60e8c3f1b6134ba0e1706890ecd774e9943f96833f79a3f08425d89b3737e935afc0233dee821f606"}' ========= master_lag: 0.40 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-06 05:08:43.121380) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:08:43.121710) ========= 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.1yd5K0JQgW/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.YJZ7q9Q4te/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005265 ========= master_lag: 0.48 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:08:43.604244) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-06 05:08:43.604529) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-06 05:08:43.664626) ========= ========= Total master_lag: 29.72 =========