builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0170 starttime: 1457071730.44 results: success (0) buildid: 20160303201126 builduid: 245f6ccc8d264144b8b463083c81e95b revision: 579da5ef8ccf4417fb472947e4cd5665f7f90a46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-03 22:08:50.435980) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-03 22:08:50.436465) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-03 22:08:50.436768) ========= 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.i16reHACz3/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.8Qq3KXTfUD/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.006026 basedir: '/builds/slave/test' ========= master_lag: 0.08 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-03 22:08:50.520123) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-03-03 22:08:50.520445) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-03-03 22:08:52.124163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:08:52.127437) ========= 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.i16reHACz3/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.8Qq3KXTfUD/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005380 ========= master_lag: 0.39 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:08:52.518255) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-03 22:08:52.518562) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-03 22:08:52.518911) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-03-03 22:08:52.519180) ========= 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.i16reHACz3/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.8Qq3KXTfUD/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-03-03 22:08:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.48M=0.002s 2016-03-03 22:08:54 (7.48 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.511080 ========= master_lag: 7.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-03-03 22:09:00.055378) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-03-03 22:09:00.055655) ========= 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.i16reHACz3/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.8Qq3KXTfUD/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.019936 ========= master_lag: 6.19 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-03-03 22:09:06.267675) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-03-03 22:09:06.267989) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 579da5ef8ccf4417fb472947e4cd5665f7f90a46 --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 579da5ef8ccf4417fb472947e4cd5665f7f90a46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.i16reHACz3/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.8Qq3KXTfUD/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-03-03 22:09:06,403 truncating revision to first 12 chars 2016-03-03 22:09:06,403 Setting DEBUG logging. 2016-03-03 22:09:06,403 attempt 1/10 2016-03-03 22:09:06,403 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/579da5ef8ccf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-03 22:09:06,678 unpacking tar archive at: fx-team-579da5ef8ccf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.531694 ========= master_lag: 5.70 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-03-03 22:09:12.496757) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-03 22:09:12.497057) ========= echo 579da5ef8ccf4417fb472947e4cd5665f7f90a46 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'579da5ef8ccf4417fb472947e4cd5665f7f90a46'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.i16reHACz3/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.8Qq3KXTfUD/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 579da5ef8ccf4417fb472947e4cd5665f7f90a46 program finished with exit code 0 elapsedTime=0.005084 script_repo_revision: '579da5ef8ccf4417fb472947e4cd5665f7f90a46' ========= master_lag: 0.11 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-03 22:09:12.607917) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-03-03 22:09:12.608197) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-03-03 22:09:14.077010) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2016-03-03 22:09:14.077287) ========= /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.i16reHACz3/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.8Qq3KXTfUD/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:09:14 INFO - MultiFileLogger online at 20160303 22:09:14 in /builds/slave/test 22:09:14 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 22:09:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:09:14 INFO - {'append_to_log': False, 22:09:14 INFO - 'base_work_dir': '/builds/slave/test', 22:09:14 INFO - 'blob_upload_branch': 'Fx-Team', 22:09:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:09:14 INFO - 'branch': 'Fx-Team', 22:09:14 INFO - 'buildbot_json_path': 'buildprops.json', 22:09:14 INFO - 'config_files': ('talos/mac_config.py',), 22:09:14 INFO - 'default_actions': ('clobber', 22:09:14 INFO - 'read-buildbot-config', 22:09:14 INFO - 'download-and-extract', 22:09:14 INFO - 'populate-webroot', 22:09:14 INFO - 'create-virtualenv', 22:09:14 INFO - 'install', 22:09:14 INFO - 'run-tests'), 22:09:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:09:14 INFO - 'download_minidump_stackwalk': True, 22:09:14 INFO - 'download_symbols': 'ondemand', 22:09:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:09:14 INFO - 'tooltool.py': '/tools/tooltool.py', 22:09:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:09:14 INFO - '/tools/misc-python/virtualenv.py')}, 22:09:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:09:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:09:14 INFO - 'installer_path': 'installer.exe', 22:09:14 INFO - 'log_level': 'info', 22:09:14 INFO - 'log_name': 'talos', 22:09:14 INFO - 'log_to_console': True, 22:09:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:09:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:09:14 INFO - 'opt_config_files': (), 22:09:14 INFO - 'pip_index': False, 22:09:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:09:14 INFO - 'cmd': ('bash', 22:09:14 INFO - '-c', 22:09:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:09:14 INFO - 'enabled': True, 22:09:14 INFO - 'halt_on_failure': False, 22:09:14 INFO - 'name': 'check_screen_resolution'},), 22:09:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:09:14 INFO - 'cmd': ('bash', 22:09:14 INFO - '-c', 22:09:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:09:14 INFO - 'enabled': True, 22:09:14 INFO - 'halt_on_failure': False, 22:09:14 INFO - 'name': 'check_screen_resolution'},), 22:09:14 INFO - 'run_cmd_checks_enabled': True, 22:09:14 INFO - 'sps_profile': False, 22:09:14 INFO - 'sps_profile_interval': 0, 22:09:14 INFO - 'suite': 'dromaeojs', 22:09:14 INFO - 'system_bits': '32', 22:09:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:09:14 INFO - 'title': 't-yosemite-r7-0170', 22:09:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:09:14 INFO - 'use_talos_json': True, 22:09:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:09:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:09:14 INFO - 'work_dir': 'build'} 22:09:14 INFO - ##### 22:09:14 INFO - ##### Running clobber step. 22:09:14 INFO - ##### 22:09:14 INFO - Running pre-action listener: _resource_record_pre_action 22:09:14 INFO - Running main action method: clobber 22:09:14 INFO - rmtree: /builds/slave/test/build 22:09:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:09:15 INFO - Running post-action listener: _resource_record_post_action 22:09:15 INFO - ##### 22:09:15 INFO - ##### Running read-buildbot-config step. 22:09:15 INFO - ##### 22:09:15 INFO - Running pre-action listener: _resource_record_pre_action 22:09:15 INFO - Running main action method: read_buildbot_config 22:09:15 INFO - Using buildbot properties: 22:09:15 INFO - { 22:09:15 INFO - "project": "", 22:09:15 INFO - "product": "firefox", 22:09:15 INFO - "script_repo_revision": "production", 22:09:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:09:15 INFO - "repository": "", 22:09:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:09:15 INFO - "buildid": "20160303201126", 22:09:15 INFO - "pgo_build": "False", 22:09:15 INFO - "basedir": "/builds/slave/test", 22:09:15 INFO - "buildnumber": 533, 22:09:15 INFO - "slavename": "t-yosemite-r7-0170", 22:09:15 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:09:15 INFO - "platform": "yosemite_r7", 22:09:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:09:15 INFO - "branch": "fx-team", 22:09:15 INFO - "repo_path": "integration/fx-team", 22:09:15 INFO - "revision": "579da5ef8ccf4417fb472947e4cd5665f7f90a46", 22:09:15 INFO - "stage_platform": "macosx64", 22:09:15 INFO - "builduid": "245f6ccc8d264144b8b463083c81e95b", 22:09:15 INFO - "slavebuilddir": "test" 22:09:15 INFO - } 22:09:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 22:09:15 INFO - Running post-action listener: _resource_record_post_action 22:09:15 INFO - ##### 22:09:15 INFO - ##### Running download-and-extract step. 22:09:15 INFO - ##### 22:09:15 INFO - Running pre-action listener: _resource_record_pre_action 22:09:15 INFO - Running main action method: download_and_extract 22:09:15 INFO - mkdir: /builds/slave/test/build/tests 22:09:15 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')]} 22:09:15 INFO - https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:09:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json 22:09:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json 22:09:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a1.en-US.mac.test_packages.json 22:09:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.test_packages.json'}, attempt #1 22:09:19 INFO - Downloaded 1277 bytes. 22:09:19 INFO - Reading from file /builds/slave/test/build/firefox-47.0a1.en-US.mac.test_packages.json 22:09:19 INFO - Using the following test package requirements: 22:09:19 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 22:09:19 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:09:19 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 22:09:19 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:09:19 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 22:09:19 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:09:19 INFO - u'jsshell-mac.zip'], 22:09:19 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:09:19 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 22:09:19 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 22:09:19 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:09:19 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 22:09:19 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:09:19 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 22:09:19 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:09:19 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 22:09:19 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 22:09:19 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:09:19 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 22:09:19 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:19 INFO - https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:09:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:09:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:09:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:09:20 INFO - Downloaded 17855123 bytes. 22:09:20 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'] 22:09:20 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 22:09:23 INFO - Return code: 0 22:09:23 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 22:09:23 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')]} 22:09:23 INFO - https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:09:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:09:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:09:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:09:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:09:25 INFO - Downloaded 17855123 bytes. 22:09:25 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'] 22:09:25 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 22:09:28 INFO - Return code: 0 22:09:28 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')]} 22:09:28 INFO - https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:09:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 22:09:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 22:09:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 22:09:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:09:29 INFO - Downloaded 10808282 bytes. 22:09:29 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'] 22:09:29 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 22:09:30 INFO - Return code: 0 22:09:30 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')]} 22:09:30 INFO - https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:09:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 22:09:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 22:09:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 22:09:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 22:09:35 INFO - Downloaded 110957717 bytes. 22:09:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 22:09:35 INFO - mkdir: /builds/slave/test/properties 22:09:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:09:35 INFO - Writing to file /builds/slave/test/properties/build_url 22:09:35 INFO - Contents: 22:09:35 INFO - build_url:https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 22:09:36 INFO - Running post-action listener: _resource_record_post_action 22:09:36 INFO - Running post-action listener: set_extra_try_arguments 22:09:36 INFO - ##### 22:09:36 INFO - ##### Running populate-webroot step. 22:09:36 INFO - ##### 22:09:36 INFO - Running pre-action listener: _resource_record_pre_action 22:09:36 INFO - Running main action method: populate_webroot 22:09:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:09:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:09:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:09:36 INFO - '--tppagecycles', 22:09:36 INFO - '7'], 22:09:36 INFO - 'tests': ['tsvgm']}}, 22:09:36 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:09:36 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:09:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:09:36 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 22:09:36 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:09:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:09:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:36 INFO - 'talos_options': ['--e10s'], 22:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:36 INFO - 'tests': ['damp', 'tps']}, 22:09:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:36 INFO - 'talos_options': ['--e10s'], 22:09:36 INFO - 'tests': ['damp', 'tps']}, 22:09:36 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:09:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:09:36 INFO - 'other': {'tests': ['a11yr', 22:09:36 INFO - 'ts_paint', 22:09:36 INFO - 'tpaint', 22:09:36 INFO - 'sessionrestore', 22:09:36 INFO - 'sessionrestore_no_auto_restore']}, 22:09:36 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:09:36 INFO - 'tests': ['a11yr', 22:09:36 INFO - 'ts_paint', 22:09:36 INFO - 'tpaint', 22:09:36 INFO - 'sessionrestore', 22:09:36 INFO - 'sessionrestore_no_auto_restore']}, 22:09:36 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:09:36 INFO - 'tests': ['a11yr', 22:09:36 INFO - 'ts_paint', 22:09:36 INFO - 'tpaint', 22:09:36 INFO - 'sessionrestore', 22:09:36 INFO - 'sessionrestore_no_auto_restore']}, 22:09:36 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:09:36 INFO - 'tests': ['a11yr', 22:09:36 INFO - 'ts_paint', 22:09:36 INFO - 'tpaint', 22:09:36 INFO - 'sessionrestore', 22:09:36 INFO - 'sessionrestore_no_auto_restore']}, 22:09:36 INFO - 'other_l64': {'tests': ['a11yr', 22:09:36 INFO - 'ts_paint', 22:09:36 INFO - 'tpaint', 22:09:36 INFO - 'sessionrestore', 22:09:36 INFO - 'sessionrestore_no_auto_restore']}, 22:09:36 INFO - 'other_nol64': {'tests': ['a11yr', 22:09:36 INFO - 'ts_paint', 22:09:36 INFO - 'tpaint', 22:09:36 INFO - 'sessionrestore', 22:09:36 INFO - 'sessionrestore_no_auto_restore']}, 22:09:36 INFO - 'svgr': {'tests': ['tsvgx', 22:09:36 INFO - 'tsvgr_opacity', 22:09:36 INFO - 'tart', 22:09:36 INFO - 'tscrollx', 22:09:36 INFO - 'cart']}, 22:09:36 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:09:36 INFO - 'tests': ['tsvgx', 22:09:36 INFO - 'tsvgr_opacity', 22:09:36 INFO - 'tart', 22:09:36 INFO - 'tscrollx', 22:09:36 INFO - 'cart']}, 22:09:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:36 INFO - 'tests': ['tp5o']}, 22:09:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:36 INFO - 'talos_options': ['--e10s'], 22:09:36 INFO - 'tests': ['tp5o']}, 22:09:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:36 INFO - 'talos_options': ['--xperf_path', 22:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:36 INFO - 'tests': ['tp5n']}, 22:09:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:36 INFO - 'talos_options': ['--e10s', 22:09:36 INFO - '--xperf_path', 22:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:36 INFO - 'tests': ['tp5n']}}, 22:09:36 INFO - 'talos.zip': {'path': '', 22:09:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:09:36 INFO - Running post-action listener: _resource_record_post_action 22:09:36 INFO - ##### 22:09:36 INFO - ##### Running create-virtualenv step. 22:09:36 INFO - ##### 22:09:36 INFO - Running pre-action listener: _resource_record_pre_action 22:09:36 INFO - Running main action method: create_virtualenv 22:09:36 INFO - Creating virtualenv /builds/slave/test/build/venv 22:09:36 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 22:09:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:09:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:09:36 INFO - Using real prefix '/tools/python27' 22:09:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:09:37 INFO - Installing distribute.............................................................................................................................................................................................done. 22:09:40 INFO - Installing pip.................done. 22:09:40 INFO - Return code: 0 22:09:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:40 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 0x10d99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa58d82630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, '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.i16reHACz3/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.8Qq3KXTfUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:09:40 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 22:09:40 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 22:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:40 INFO - 'HOME': '/Users/cltbld', 22:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:40 INFO - 'LOGNAME': 'cltbld', 22:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:40 INFO - 'MOZ_NO_REMOTE': '1', 22:09:40 INFO - 'NO_EM_RESTART': '1', 22:09:40 INFO - 'PAGER': '/bin/cat', 22:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:40 INFO - 'PWD': '/builds/slave/test', 22:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:40 INFO - 'SHELL': '/bin/bash', 22:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:09:40 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:40 INFO - 'USER': 'cltbld', 22:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:40 INFO - 'XPC_FLAGS': '0x0', 22:09:40 INFO - 'XPC_SERVICE_NAME': '0', 22:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:40 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 22:09:40 INFO - Cleaning up... 22:09:40 INFO - Return code: 0 22:09:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:40 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 0x10d99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa58d82630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, '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.i16reHACz3/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.8Qq3KXTfUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:09:40 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 22:09:40 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 22:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:40 INFO - 'HOME': '/Users/cltbld', 22:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:40 INFO - 'LOGNAME': 'cltbld', 22:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:40 INFO - 'MOZ_NO_REMOTE': '1', 22:09:40 INFO - 'NO_EM_RESTART': '1', 22:09:40 INFO - 'PAGER': '/bin/cat', 22:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:40 INFO - 'PWD': '/builds/slave/test', 22:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:40 INFO - 'SHELL': '/bin/bash', 22:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:09:40 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:40 INFO - 'USER': 'cltbld', 22:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:40 INFO - 'XPC_FLAGS': '0x0', 22:09:40 INFO - 'XPC_SERVICE_NAME': '0', 22:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:41 INFO - Downloading/unpacking psutil>=0.7.1 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:09:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:43 INFO - Installing collected packages: psutil 22:09:43 INFO - Running setup.py install for psutil 22:09:43 INFO - building 'psutil._psutil_osx' extension 22:09:43 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 22:09:43 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 22:09:43 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 22:09:43 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 22:09:44 INFO - building 'psutil._psutil_posix' extension 22:09:44 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 22:09:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:09:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:09:44 INFO - ^ 22:09:44 INFO - 1 warning generated. 22:09:44 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 22:09:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:44 INFO - Successfully installed psutil 22:09:44 INFO - Cleaning up... 22:09:44 INFO - Return code: 0 22:09:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:09:44 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')]} 22:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:44 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')]} 22:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:44 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 0x10d99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa58d82630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, '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.i16reHACz3/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.8Qq3KXTfUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:09:44 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 22:09:44 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 22:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:44 INFO - 'HOME': '/Users/cltbld', 22:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:44 INFO - 'LOGNAME': 'cltbld', 22:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:44 INFO - 'MOZ_NO_REMOTE': '1', 22:09:44 INFO - 'NO_EM_RESTART': '1', 22:09:44 INFO - 'PAGER': '/bin/cat', 22:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:44 INFO - 'PWD': '/builds/slave/test', 22:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:44 INFO - 'SHELL': '/bin/bash', 22:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:09:44 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:44 INFO - 'USER': 'cltbld', 22:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:44 INFO - 'XPC_FLAGS': '0x0', 22:09:44 INFO - 'XPC_SERVICE_NAME': '0', 22:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:09:47 INFO - Installing collected packages: mozsystemmonitor 22:09:47 INFO - Running setup.py install for mozsystemmonitor 22:09:47 INFO - Successfully installed mozsystemmonitor 22:09:47 INFO - Cleaning up... 22:09:47 INFO - Return code: 0 22:09:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:09:47 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')]} 22:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:47 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')]} 22:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:47 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 0x10d99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa58d82630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, '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.i16reHACz3/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.8Qq3KXTfUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:09:47 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 22:09:47 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 22:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:47 INFO - 'HOME': '/Users/cltbld', 22:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:47 INFO - 'LOGNAME': 'cltbld', 22:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:47 INFO - 'MOZ_NO_REMOTE': '1', 22:09:47 INFO - 'NO_EM_RESTART': '1', 22:09:47 INFO - 'PAGER': '/bin/cat', 22:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:47 INFO - 'PWD': '/builds/slave/test', 22:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:47 INFO - 'SHELL': '/bin/bash', 22:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:09:47 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:47 INFO - 'USER': 'cltbld', 22:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:47 INFO - 'XPC_FLAGS': '0x0', 22:09:47 INFO - 'XPC_SERVICE_NAME': '0', 22:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:47 INFO - Downloading/unpacking blobuploader==1.2.4 22:09:47 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 22:09:47 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 22:09:47 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 22:09:47 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 22:09:47 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 22:09:47 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 22:09:49 INFO - Downloading blobuploader-1.2.4.tar.gz 22:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:09:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:09:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 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 22:09:50 INFO - Downloading docopt-0.6.1.tar.gz 22:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:09:51 INFO - Installing collected packages: blobuploader, requests, docopt 22:09:51 INFO - Running setup.py install for blobuploader 22:09:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:09:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:09:51 INFO - Running setup.py install for requests 22:09:51 INFO - Running setup.py install for docopt 22:09:51 INFO - Successfully installed blobuploader requests docopt 22:09:51 INFO - Cleaning up... 22:09:52 INFO - Return code: 0 22:09:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:52 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 0x10d99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa58d82630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, '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.i16reHACz3/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.8Qq3KXTfUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:09:52 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 22:09:52 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 22:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:52 INFO - 'HOME': '/Users/cltbld', 22:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:52 INFO - 'LOGNAME': 'cltbld', 22:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:52 INFO - 'MOZ_NO_REMOTE': '1', 22:09:52 INFO - 'NO_EM_RESTART': '1', 22:09:52 INFO - 'PAGER': '/bin/cat', 22:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:52 INFO - 'PWD': '/builds/slave/test', 22:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:52 INFO - 'SHELL': '/bin/bash', 22:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:09:52 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:52 INFO - 'USER': 'cltbld', 22:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:52 INFO - 'XPC_FLAGS': '0x0', 22:09:52 INFO - 'XPC_SERVICE_NAME': '0', 22:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:52 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-lAHRxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:52 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ymRkIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:52 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-3z83zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:52 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-c8t6ZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:52 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-8YFq2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:52 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-8LbQcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:52 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-gBr_E8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-HYr1I4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-jHh2kQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-AqSfE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Rl8mRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-in57ed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-0U47xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Md8uGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-FzQ2dS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-56d6v4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:53 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-gc4EIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:09:54 INFO - Running setup.py install for manifestparser 22:09:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:09:54 INFO - Running setup.py install for mozcrash 22:09:54 INFO - Running setup.py install for mozdebug 22:09:54 INFO - Running setup.py install for mozdevice 22:09:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:09:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:09:54 INFO - Running setup.py install for mozfile 22:09:54 INFO - Running setup.py install for mozhttpd 22:09:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:09:54 INFO - Running setup.py install for mozinfo 22:09:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:09:55 INFO - Running setup.py install for mozInstall 22:09:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:09:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:09:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:09:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:09:55 INFO - Running setup.py install for mozleak 22:09:55 INFO - Running setup.py install for mozlog 22:09:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:09:55 INFO - Running setup.py install for moznetwork 22:09:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:09:55 INFO - Running setup.py install for mozprocess 22:09:55 INFO - Running setup.py install for mozprofile 22:09:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:09:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:09:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:09:56 INFO - Running setup.py install for mozrunner 22:09:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:09:56 INFO - Running setup.py install for mozscreenshot 22:09:56 INFO - Running setup.py install for moztest 22:09:56 INFO - Running setup.py install for mozversion 22:09:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:09:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:09:56 INFO - Cleaning up... 22:09:56 INFO - Return code: 0 22:09:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:56 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 0x10d99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa58d82630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, '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.i16reHACz3/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.8Qq3KXTfUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:09:56 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 22:09:56 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 22:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:56 INFO - 'HOME': '/Users/cltbld', 22:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:56 INFO - 'LOGNAME': 'cltbld', 22:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:56 INFO - 'MOZ_NO_REMOTE': '1', 22:09:56 INFO - 'NO_EM_RESTART': '1', 22:09:56 INFO - 'PAGER': '/bin/cat', 22:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:56 INFO - 'PWD': '/builds/slave/test', 22:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:56 INFO - 'SHELL': '/bin/bash', 22:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:09:56 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:56 INFO - 'USER': 'cltbld', 22:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:56 INFO - 'XPC_FLAGS': '0x0', 22:09:56 INFO - 'XPC_SERVICE_NAME': '0', 22:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:56 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Ir5G5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-HoF3wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ulmrI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-u7v1GD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-FyPo3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-E9X_vE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-6GRfpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-_AlIGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-tvcBbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VGTL79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:57 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)) 22:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:57 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-YlXn0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:58 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)) 22:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:58 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-FsS1WJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:58 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)) 22:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:58 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-TCHEIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:58 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)) 22:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:58 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-KqQKpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:58 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)) 22:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:58 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-elqBQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:58 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)) 22:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:58 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-2_RkME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:58 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)) 22:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:58 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-YPmLIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:58 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)) 22:09:58 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)) 22:09:58 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)) 22:09:58 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)) 22:09:58 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)) 22:09:58 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)) 22:09:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:09: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 22:10:01 INFO - Downloading blessings-1.6.tar.gz 22:10:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:10:01 INFO - Installing collected packages: blessings 22:10:01 INFO - Running setup.py install for blessings 22:10:01 INFO - Successfully installed blessings 22:10:01 INFO - Cleaning up... 22:10:01 INFO - Return code: 0 22:10:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:01 INFO - Reading from file tmpfile_stdout 22:10:01 INFO - Current package versions: 22:10:01 INFO - blessings == 1.6 22:10:01 INFO - blobuploader == 1.2.4 22:10:01 INFO - docopt == 0.6.1 22:10:01 INFO - manifestparser == 1.1 22:10:01 INFO - mozInstall == 1.12 22:10:01 INFO - mozcrash == 0.16 22:10:01 INFO - mozdebug == 0.1 22:10:01 INFO - mozdevice == 0.48 22:10:01 INFO - mozfile == 1.2 22:10:01 INFO - mozhttpd == 0.7 22:10:01 INFO - mozinfo == 0.9 22:10:01 INFO - mozleak == 0.1 22:10:01 INFO - mozlog == 3.1 22:10:01 INFO - moznetwork == 0.27 22:10:01 INFO - mozprocess == 0.22 22:10:01 INFO - mozprofile == 0.28 22:10:01 INFO - mozrunner == 6.11 22:10:01 INFO - mozscreenshot == 0.1 22:10:01 INFO - mozsystemmonitor == 0.0 22:10:01 INFO - moztest == 0.7 22:10:01 INFO - mozversion == 1.4 22:10:01 INFO - psutil == 3.1.1 22:10:01 INFO - requests == 1.2.3 22:10:01 INFO - wsgiref == 0.1.2 22:10:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:01 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 0x10d99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa58d82630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, '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.i16reHACz3/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.8Qq3KXTfUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:10:01 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 22:10:01 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 22:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:01 INFO - 'HOME': '/Users/cltbld', 22:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:01 INFO - 'LOGNAME': 'cltbld', 22:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:01 INFO - 'MOZ_NO_REMOTE': '1', 22:10:01 INFO - 'NO_EM_RESTART': '1', 22:10:01 INFO - 'PAGER': '/bin/cat', 22:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:01 INFO - 'PWD': '/builds/slave/test', 22:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:01 INFO - 'SHELL': '/bin/bash', 22:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:10:01 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:01 INFO - 'USER': 'cltbld', 22:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:01 INFO - 'XPC_FLAGS': '0x0', 22:10:01 INFO - 'XPC_SERVICE_NAME': '0', 22:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 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)) 22:10:01 INFO - Cleaning up... 22:10:01 INFO - Return code: 0 22:10:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa58d82630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, '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.i16reHACz3/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.8Qq3KXTfUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:10:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:10:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:10:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:02 INFO - 'HOME': '/Users/cltbld', 22:10:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:02 INFO - 'LOGNAME': 'cltbld', 22:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:02 INFO - 'MOZ_NO_REMOTE': '1', 22:10:02 INFO - 'NO_EM_RESTART': '1', 22:10:02 INFO - 'PAGER': '/bin/cat', 22:10:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:02 INFO - 'PWD': '/builds/slave/test', 22:10:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:02 INFO - 'SHELL': '/bin/bash', 22:10:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:10:02 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:10:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:02 INFO - 'USER': 'cltbld', 22:10:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:02 INFO - 'XPC_FLAGS': '0x0', 22:10:02 INFO - 'XPC_SERVICE_NAME': '0', 22:10:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:02 INFO - Downloading/unpacking jsonschema 22:10: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 22:10: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 22:10: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 22:10: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 22:10: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 22:10: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 22:10:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:10:04 INFO - Downloading/unpacking functools32 (from jsonschema) 22:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:05 INFO - Downloading functools32-3.2.3-2.tar.gz 22:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:10:05 INFO - warning: no files found matching '*.txt' 22:10:05 INFO - no previously-included directories found matching 'build' 22:10:05 INFO - no previously-included directories found matching 'dist' 22:10:05 INFO - no previously-included directories found matching '.git*' 22:10:05 INFO - Installing collected packages: jsonschema, functools32 22:10:05 INFO - Running setup.py install for functools32 22:10:05 INFO - warning: no files found matching '*.txt' 22:10:05 INFO - no previously-included directories found matching 'build' 22:10:05 INFO - no previously-included directories found matching 'dist' 22:10:05 INFO - no previously-included directories found matching '.git*' 22:10:05 INFO - Successfully installed jsonschema functools32 22:10:05 INFO - Cleaning up... 22:10:05 INFO - Return code: 0 22:10:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d99aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa58d82630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, '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.i16reHACz3/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.8Qq3KXTfUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:10:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:05 INFO - 'HOME': '/Users/cltbld', 22:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:05 INFO - 'LOGNAME': 'cltbld', 22:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:05 INFO - 'MOZ_NO_REMOTE': '1', 22:10:05 INFO - 'NO_EM_RESTART': '1', 22:10:05 INFO - 'PAGER': '/bin/cat', 22:10:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:05 INFO - 'PWD': '/builds/slave/test', 22:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:05 INFO - 'SHELL': '/bin/bash', 22:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:10:05 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:05 INFO - 'USER': 'cltbld', 22:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:05 INFO - 'XPC_FLAGS': '0x0', 22:10:05 INFO - 'XPC_SERVICE_NAME': '0', 22:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:05 INFO - Downloading/unpacking flake8 22:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:10:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:10:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:10:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:10:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:10:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:10:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:10:09 INFO - no previously-included directories found matching 'docs/_build' 22:10:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:10:09 INFO - Running setup.py install for pep8 22:10:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:10:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:10:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:10:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:10:09 INFO - no previously-included directories found matching 'docs/_build' 22:10:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:10:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:10:09 INFO - Cleaning up... 22:10:09 INFO - Return code: 0 22:10:09 INFO - Running post-action listener: _resource_record_post_action 22:10:09 INFO - Running post-action listener: _start_resource_monitoring 22:10:09 INFO - Starting resource monitoring. 22:10:09 INFO - ##### 22:10:09 INFO - ##### Running install step. 22:10:09 INFO - ##### 22:10:09 INFO - Running pre-action listener: _resource_record_pre_action 22:10:09 INFO - Running main action method: install 22:10:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:09 INFO - Reading from file tmpfile_stdout 22:10:09 INFO - Detecting whether we're running mozinstall >=1.0... 22:10:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:10:10 INFO - Reading from file tmpfile_stdout 22:10:10 INFO - Output received: 22:10:10 INFO - Usage: mozinstall [options] installer 22:10:10 INFO - Options: 22:10:10 INFO - -h, --help show this help message and exit 22:10:10 INFO - -d DEST, --destination=DEST 22:10:10 INFO - Directory to install application into. [default: 22:10:10 INFO - "/builds/slave/test"] 22:10:10 INFO - --app=APP Application being installed. [default: firefox] 22:10:10 INFO - mkdir: /builds/slave/test/build/application 22:10:10 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'] 22:10:10 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 22:10:43 INFO - Reading from file tmpfile_stdout 22:10:43 INFO - Output received: 22:10:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:10:43 INFO - Running post-action listener: _resource_record_post_action 22:10:43 INFO - ##### 22:10:43 INFO - ##### Running run-tests step. 22:10:43 INFO - ##### 22:10:43 INFO - Running pre-action listener: _resource_record_pre_action 22:10:43 INFO - Running main action method: run_tests 22:10:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:10:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:10:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:10:43 INFO - 2016-03-03 22:10:43.726 screenresolution[2223:17555] starting screenresolution argv=screenresolution get 22:10:43 INFO - 2016-03-03 22:10:43.728 screenresolution[2223:17555] Display 0: 1600x1200x32@60 22:10:43 INFO - 2016-03-03 22:10:43.734 screenresolution[2224:17558] starting screenresolution argv=screenresolution list 22:10:43 INFO - Available Modes on Display 0 22:10:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:10:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:10:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:10:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:10:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:10:43 INFO - Intel Iris: 22:10:43 INFO - Chipset Model: Intel Iris 22:10:43 INFO - Type: GPU 22:10:43 INFO - Bus: Built-In 22:10:43 INFO - VRAM (Dynamic, Max): 1536 MB 22:10:43 INFO - Vendor: Intel (0x8086) 22:10:43 INFO - Device ID: 0x0a2e 22:10:43 INFO - Revision ID: 0x0009 22:10:43 INFO - Displays: 22:10:43 INFO - Display: 22:10:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:10:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:10:43 INFO - Display Serial Number: 1600x1200 60 22:10:43 INFO - Main Display: Yes 22:10:43 INFO - Mirror: Off 22:10:43 INFO - Online: Yes 22:10:43 INFO - Rotation: Supported 22:10:43 INFO - Return code: 0 22:10:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:10:43 INFO - Python 2.7.3 22:10:43 INFO - Return code: 0 22:10:43 INFO - grabbing minidump binary from tooltool 22:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:43 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 0x10d83ed30>, '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 0x10d662db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d835b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:10:43 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 22:10:43 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 22:10:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:10:44 INFO - Return code: 0 22:10:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:10:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:10:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:10:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:10:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:10:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:10:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:10:45 INFO - Return code: 0 22:10:45 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', 'https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 22:10:45 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 https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --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 22:10:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.i16reHACz3/Render', 22:10:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:45 INFO - 'HOME': '/Users/cltbld', 22:10:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:45 INFO - 'LOGNAME': 'cltbld', 22:10:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:10:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:45 INFO - 'MOZ_NO_REMOTE': '1', 22:10:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:10:45 INFO - 'NO_EM_RESTART': '1', 22:10:45 INFO - 'PAGER': '/bin/cat', 22:10:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:45 INFO - 'PWD': '/builds/slave/test', 22:10:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:10:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:45 INFO - 'SHELL': '/bin/bash', 22:10:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Qq3KXTfUD/Listeners', 22:10:45 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:10:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:45 INFO - 'USER': 'cltbld', 22:10:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:45 INFO - 'XPC_FLAGS': '0x0', 22:10:45 INFO - 'XPC_SERVICE_NAME': '0', 22:10:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:45 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', 'https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 22:10:45 INFO - mozversion application_buildid: 20160303201126 22:10:45 INFO - mozversion application_changeset: 579da5ef8ccf4417fb472947e4cd5665f7f90a46 22:10:45 INFO - mozversion application_display_name: Nightly 22:10:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:10:45 INFO - mozversion application_name: Firefox 22:10:45 INFO - mozversion application_remotingname: firefox 22:10:45 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:10:45 INFO - mozversion application_vendor: Mozilla 22:10:45 INFO - mozversion application_version: 47.0a1 22:10:45 INFO - mozversion platform_buildid: 20160303201126 22:10:45 INFO - mozversion platform_changeset: 579da5ef8ccf4417fb472947e4cd5665f7f90a46 22:10:45 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:10:45 INFO - mozversion platform_version: 47.0a1 22:10:45 INFO - using testdate: 1457071845 22:10:45 INFO - actual date: 1457071845 22:10:45 INFO - starting webserver on 'localhost:50088' 22:10:45 INFO - SUITE-START | Running 2 tests 22:10:45 INFO - TEST-START | dromaeo_css 22:10:45 INFO - operating with platform_type : mac_ 22:10:45 INFO - Initialising browser for dromaeo_css test... 22:10:45 INFO - TEST-INFO | started process 2242 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmp6xjIdf/profile http://localhost:50088/getInfo.html) 22:10:47 INFO - PROCESS | 2242 | __metrics Screen width/height:1600/1200 22:10:47 INFO - PROCESS | 2242 | colorDepth:24 22:10:47 INFO - PROCESS | 2242 | Browser inner width/height: 1024/689 22:10:47 INFO - PROCESS | 2242 | __metrics 22:10:47 INFO - PROCESS | 2242 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:10:47 INFO - PROCESS | 2242 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:10:48 INFO - TEST-INFO | 2242: exit 0 22:10:48 INFO - Browser initialized. 22:10:48 INFO - Running cycle 1/1 for dromaeo_css test... 22:10:48 INFO - TEST-INFO | started process 2245 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmp6xjIdf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:11:00 INFO - PROCESS | 2245 | RSS: Main: 243519488 22:11:00 INFO - PROCESS | 2245 | 22:11:51 INFO - PROCESS | 2245 | 1457071911086 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] 22:14:01 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:50088/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50088/tests/dromaeo/cssquery-ext.html) 22:14:02 INFO - PROCESS | 2245 | RSS: Main: 365527040 22:14:02 INFO - PROCESS | 2245 | 22:17:05 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:50088/tests/dromaeo/cssquery-ext.html (next: http://localhost:50088/tests/dromaeo/cssquery-jquery.html) 22:17:05 INFO - PROCESS | 2245 | RSS: Main: 369979392 22:17:05 INFO - PROCESS | 2245 | 22:20:07 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:50088/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50088/tests/dromaeo/cssquery-mootools.html) 22:20:08 INFO - PROCESS | 2245 | RSS: Main: 372060160 22:20:08 INFO - PROCESS | 2245 | 22:23:10 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:50088/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50088/tests/dromaeo/cssquery-prototype.html) 22:23:11 INFO - PROCESS | 2245 | RSS: Main: 328966144 22:23:11 INFO - PROCESS | 2245 | 22:26:14 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:50088/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50088/tests/dromaeo/cssquery-yui.html) 22:26:14 INFO - PROCESS | 2245 | RSS: Main: 331436032 22:26:14 INFO - PROCESS | 2245 | 22:29:17 INFO - PROCESS | 2245 | Cycle 1(1): loaded http://localhost:50088/tests/dromaeo/cssquery-yui.html (next: http://localhost:50088/tests/dromaeo/cssquery-dojo.html) 22:29:17 INFO - PROCESS | 2245 | RSS: Main: 455110656 22:29:17 INFO - PROCESS | 2245 | 22:29:17 INFO - PROCESS | 2245 | __start_tp_report 22:29:17 INFO - PROCESS | 2245 | _x_x_mozilla_page_load 22:29:17 INFO - PROCESS | 2245 | _x_x_mozilla_page_load_details 22:29:17 INFO - PROCESS | 2245 | |i|pagename|runs| 22:29:17 INFO - PROCESS | 2245 | |0;dojo.html;3131.9843400782993;3410.880619178329;3441.7074548663363;3463.1342164458883;3496.178398076453;5514.641548299358;5563.805266815662;5584.58115641327;5600.27196464459;5633.408492108326;5626.971865140678;5630.352509461413;5677;5742.79900203493;5754.367019627837;5008.3238762767105;5039.168537191368;5058.190689489682;5089.287499750038;5202.271681964528;5125.871853203663;5148.6910785353;5171.612129090315;5209.687418754868;5240.318758561381;4987.900241995158;4994.475580064077;5065.088284108859;5092.185250359925;5101.260317253996;5109.5145961134;5120.564751996085;5129.5383415492415;5136.486351364871;5140.691558506502;5016.247562865563;5065.39215294167;5080.466551012128;5090.9236361454605;5095.414027386824;5428;5530.474604912567;5535.280413546254;5567.192757050205;5598.692071936042;5122.796142906414;6135.477655820245;6155.692215389213;6157;6167.537434692371;5390.948764990829;6150.384961503859;6228.159198508234;6233.407826256544;6258.687065646699;5573.108302671593;5684.37471878093;5695.530071381195;5704.942950570441;5775.16260142277;6265.279492858289;6293.968530157412;6317.210348706411;6319.652419116901;6325.746970121144;5655.4344565543115;5694.1458781183155;6016.909746353808;6109;6116.143739876421;6083;6136.048912418582;6138.355472675305;6215.844603884849;6247.281562620356;6138.202033735586;6142.529470848956;6148.631082135086;6162.291336496271;6163.722632481549;6162.322144564095;6164.938350616526;6180.103884936659;6184.938150618526;6188.133661287423;4727.787249573777;4800.35195248644;4825.4933232010835;4846.200376937836;4895.020995800783;4350.151720414488;4353.194658988097;4371.540988196193;4379.1241751649795;4393.670474714345;5578.3584887738425;5649.180868774005;5666.065099258659;5672.886542269214;5679.4320567943705;6108.053251745987;6131.284916201153;6235;6247.312795592481;6271.937280627227;5649.943500565024;5653.858653533613;5667.688277144796;5682.374938756733;5737.168110624019;5296.788128474818;5313.017091837945;5323.494268044529;5325.174597937326;5329.9467005330225;6192.690365481798;6201.2868520118955;6282.748690052346;6283.465905398092;6289.685515724104;6227.875442491216;6254.374562543891;6256;6265.874682506415;6287.811365659038;6257.217847769029;6265.122882796321;6273.592216505913;6274.309825919054;6286.622802631857;5996.4603185713495;6022;6111.419415155553;6136.079588061826;6155.815325540241;6175.62946223228;6246.156768836149;6251.156093927261;6253.624782513064;6254.718537665907;6068.787592434243;6069.635821850703;6073.362296958756;6073.908891366721;6085;5850.297964244318;5966.373530779206;6000.819975400745;6025.386296976899;6121.938780612137;5837.153612726046;5851;5856.531477481706;5859.150423188699;5893.145493903275;5929.940700593112;5987.760489580368;6005.729742161526;6008.909866351921;6016.42843929826;5746.13807928814;5835.2122463466885;5874.441928016831;5880.206172166702;5945.078512830518 22:29:17 INFO - PROCESS | 2245 | |1;ext.html;467.1381301498738;570.9551520717085;571.362930332679;622.701103470334;633.3571425003622;34988.60045598176;35953.2809343813;38459;38836.41745373821;38965.80517097418;34561.23510119191;41667.79166104165;42177.7891110544;42251.788741056254;42284;9507.667231646876;9764.658236961708;9764.925858155597;9774.315797894114;9789.167920726746;9576.377535460173;9743.610255589803;9747.074027967332;9753.463559504224;9757.073078057572;9764;9879.407235565854;9914.55384507699;9924.603015879356;9993;9767.658131965383;9882.407055576688;9883.01169883006;9889.406635601887;9894.80210395788;9584.329096963216;9734.805303893952;9734.951325243328;9743.415395076247;9744.75638109053;40317.59682403168;40671;40676.389854152214;40689.593104068874;40749.79625101855;2609.2041927212194;3156.589643346373;3181.1569933967517;3256.0882952773263;3419.6238413774463;28411.431771364456;28473;28753;28878.133655990354;28912.71087289142;2711.7830573554265;2756.944861102787;2763.958540621892;2766.225456872079;2838.3613686920485;489158;490893;491231;491582;493296;53315.33558305212;63967.161970281;65055;65127.67436162789;65137.67431162909;69658;71755.64122179356;72029.63985180145;72168;72218;12670.619881403572;13256.337183140804;14588;15047.398104075934;15202.46791362297;11420.029297509638;11450.026747726537;11462.770744585061;11472.541098355972;11474.254173478697;20640.69038964417;20967.161313547593;21031.789682102983;21054;21553.461163470725;8696.17386348296;8962.32782541312;8963.078285177093;8968.148025937526;9070.092990701009;958.5542722633869;963.5326866469757;1072.7210925159359;1090.2858627598935;1171.654361963206;28571.28571785722;28677;29180.854095729384;29250.268743281587;29282.853585731937;968.4334664221368;1034.234666346913;1039.9584016639249;1048.3290693955873;1185.8141858141857;14345.6413589659;14375.209363485108;14456.710865782625;14762.74516666074;14794;528457;536381;536772;537423;537444;46931.06137877223;53786;56215.37846215346;56216.718916405975;56248.593785155696;1251.4681260464267;1279.8848103670714;1374.0519041861085;1383.204657322064;1408.3028900694221;27930.44139117247;28253.15240542787;28772.84908603716;29474.852625736734;29488.55767163537;46137.769311154574;46554.13783448624;46826.29760553526;46876;47025.824354390046;380627.0968645139;382399;383166;383186;383983.0800845978;28202.73087711017;31738.682613173572;32739.836300818344;32750;32788.344233115684;39968.80015599904;40311;40500;40569.391459128725;41433.99207115012;33238.833805830815;34384.82807585946;34780.65219347875;34884.127896802784;35030.124246893014;33161.83419082889;33558;34014.149646259044;34307;35585;28760.424791504473;34136;34465.65534344625;34514;35532.82233588816;30372.93694720674;30646.233844154074;30827.537586935767;30958.84520577383;30963.225919352197;19037.04814759229;22607.227760352882;23144;24011.27966161018;24468.51062978766 22:29:17 INFO - PROCESS | 2245 | |2;jquery.html;321.23706197780274;351.1327022255029;355.7100962715387;359.47875580408396;362.80952499937547;19294.807051929478;19443.930583817884;20777.688334674993;20784.584308313824;20794.99999999998;17930.820691793077;18327.358542451017;18542.165602547884;18552.536186595342;18582.977936213538;5522.889542209164;5773.913391299134;5843.240378750777;5846.09385545239;5864;4171.937420938689;4355.063661312817;4366.170427618742;4369.97305633176;4833.371661683994;4462.3975763271865;6341.429271365576;6344.777932772354;6351.079093531435;6356.936430635704;5944.910826337608;6292.2134733158355;6314.463270622003;6343.651099189566;6349.047642853563;3772.6793222576116;5441.945580544223;5458.1169496523635;5461.208124821918;5467.507924286793;23094.65358019631;23152.884235578884;24089.397765056015;24110.638340424906;24169.033238670432;2059.773424923257;2201.8128459081;2314.5833749925005;2583.4962182374443;2728.1679087878188;9736.41581505112;10047.899521004769;11898.10764192689;12246.510139594406;14707.55877323682;1677.3039188736782;1686.4603326935385;1705.891170739017;1734.1849330814498;1783.0817129178483;108452;111055.99999999919;112350;113530.43234783856;113852.4307378466;10985.01134897863;11007.944960275147;11229.438528073564;11245.887541124484;11350.829737553944;13541.39063742134;13673;13688.65778355549;13702.93148534251;13712.65718357029;7864.606769661494;8046.0344758629335;8053.8791918120105;8127.918720812834;8132.512049277062;7955.641996110189;8000.199980002069;8030.879536806952;8080.969636386355;8083.878741818878;4438.378626992224;4469.41897553316;4471.754053527087;4485.215087359699;4521.525239849769;1707.3426730708766;1810.2396993262598;1862.897540635251;1894.242303078752;1900.078461945955;844.966201351939;925;927.9257659387233;956.8756061711933;991.4844280973854;14405.92797036008;19743.21027158926;20046.89976550108;20100.899495502723;20462;795.383577727266;939.9953000234955;958.2765012415582;961.5769061612868;1019.4800651667555;10281.794364112677;10581.576736930436;10617;10633.73415664599;10651;639174;646840;651350;652457;655361.723191381;102527;120339.19491207639;122966.15550766746;126255.3687231558;127239.36380318039;942.853857652065;950.4772375193616;961.6730311694058;963.1861077389594;997.4514017290461;17183.31266749316;17450.91274543619;17609.735853961945;17855.642887142443;17990.730139048188;51300.48699512957;51803.48196517987;52834.73582632062;52923.47076529185;54653;76103.61948190223;76564.85152722825;77188.61405692935;77227.61386193032;77434;6904.551204171713;7689.3464055555905;7868.095169055403;7981.561014144278;8127.471714338549;9449.574769135543;12312.569060082855;12920.0309976754;12929.094963352474;13286;11235.382053986783;11508.654740357802;11559.479823407799;11574.074074074222;11596.536138554364;10699;11012.669619911416;11150;11453.770924581295;11513.769724605629;11517.136214784092;11547.422628868691;11703.063754899418;11874.881251187722;11896.988755955494;6877.58734475933;7831.686732530635;7882.832221139232;8137.471064380797;8201.507909525448;11291.491882865126;11305.773884522428;11593.82609260845;11628.6511404658;11697 22:29:17 INFO - PROCESS | 2245 | |3;mootools.html;1386.7850483175098;1519.073365247199;1525.5880912153716;1756.5959829239275;1933.8742981706189;7822.374210063188;8445.66217351306;8593.527355995426;8598.484090954547;8600.8709869352;8352;8588.398812083156;8590.699325523608;8604.483730976146;8617.87073193901;2140.2295173737443;2385.6183010718287;2430.817688673352;2455.1897873701637;2457.213691618683;2462.5813611685967;2467.679201703776;2471.0856982916257;2472.7898128659085;2474.604063349864;2301.976980230193;2306.780855818703;2353.082297903819;2428.441458464554;2443.743406942272;2402.9279121626205;2444.8899799509063;2445.6942882139733;2452.534018536481;2464.112919349033;2427.1383658801046;2433.829631925766;2453.5092981403614;2455.2511483997373;2461.9876900615563;8674.869876951787;8725.520096394695;8754.080821513713;8769.561521923943;8770.824583508358;5176.197689358156;5300.628955973083;5321.494458026481;5331;5332.600054995852;3020.41101985113;3514.1039035046156;3664.798436086014;3665.157013886794;3685.023468780775;1029.7322696098993;1056.6513050693252;1060.5470505407563;1072.131573425528;1072.3137192197;12001.519939202508;12083.637490875288;12087.818682719766;12090.939545302217;12113.576024839089;4757.119932812441;4808.63982481464;4835.298881662139;4835.8065677372515;4880.755962201875;10132.088112069949;10142.442165680959;10145.594176232868;10156.593736250616;10163.339382940085;5304.071787437183;5306.575473962073;5313.0436521425745;5316.308879845596;5321.760520776575;4309.956900430955;4452.443444569429;4658.1848176568965;4764.857054288377;4789.114013907439;3868.6518213360932;3928.057266256078;3938.487996560486;3963.742356746802;3976.8409263629133;1287.0475847872694;1297.1827748518374;1299.27240745183;1308.6662900960384;1310.6592286005707;1120.1430905357408;1140.4183866228118;1141.50915106505;1367.7811550151928;1368.6988862450246;8149.959250203711;8214.958925205336;8257.42198046131;8261.752147435587;8266.875996860052;504.954743680585;516.3622925686774;517.5936889541767;518.4219595151454;523.0689372916215;6171.537134714825;6171.814845554641;6226.688665566794;6231.2522497300615;6237.594556353823;10289.22830787694;10629.787404251872;10775.730606734738;10808.297460665031;10817.675469735921;9966.900330996597;10547.84178237327;10555.050045495942;10647.574097036031;10683.83974240387;7994.0407151142235;8147.959260203661;8180.590970451453;8218.589070546448;8227.053888802744;7917.7624671259955;7929.088154862265;7998.080220774453;8032;8054.919450805417;9184.908150918405;9279.62881484733;9396.812063758824;9449.905500944902;9560.665376711782;9712.805743884941;9794.510274486105;9811.607535698493;9862.704118876445;10068.39589624625;6801.387875091265;6984.3364880336285;7217.927820721936;7499.812504687427;7626.923730762844;8153.306968907709;8180.509169449852;8195.67217311301;8233.917660823316;8239.340852731648;7158.355747982707;7288.526245794006;7314.414846812136;7404.185539590749;7454.142773581108;6038.486728628116;7125.5368401053765;7147.213806481179;7230.875272112369;7281.963590182015;6477;7613.505122167041;7668.309852113113;7675.654595543326;7676.961615191888;6366.045093235866;6659.800205993828;6909.378155965987;7018.368346848808;7030.261822508563;6955.304469553004;7074.257202993612;7080.185778635523;7104.7513337035025;7108.857822843411 22:29:17 INFO - PROCESS | 2245 | |4;prototype.html;313.1904028087394;317.25919976854044;322.9192701824544;327.62486952439457;331.0482363205783;6489.351064893508;6571.5071369647285;6576;6597.967010164954;6633.270340262573;6423.325550817167;6506.674666266679;6530.95504719245;6553.541252112354;6585.506087043467;4585.862424127265;4710.458297295821;4740.217864052427;4849.854504364875;4874.756262186886;4795.784189711454;4808.831690890819;4810.35060266863;4814.181589129839;4822.156122678535;4718.221493453576;4740.620750339965;4750.501197374278;4774.665773395864;4782.545658162469;3888.494495715539;4572.679912406133;4662.160811054043;4817.060673168716;4854.781534830941;4104.322786740185;4106.650934670557;4111.218868414991;4113.835446582133;4114.300568903263;6303.7163327649905;6313.621182729051;6366.968165159191;6519.608823470559;6523.315051919528;5628.42404126841;5633.746481408347;5634.577406694514;5642.319898048435;5664.773409063632;5635.746391412396;6839;6945.305469453065;6962.512624116314;6972.895406568906;4314.395984562164;4504.617107545862;4528.954710452919;4542.613877820356;4600.309953506967;7382;7394.408447324201;7457.813554661123;7469.365103966169;7478.439117066242;4601.125786100697;4605.815767369268;4607.516210797885;4608.769561521911;4609.492955774862;4656.324832899279;4660.13787449323;4669.042846216517;4669.2062349400685;4671.5094915034115;5546.611737178358;5548.805791797348;5549.361823390281;5549.500544950974;5549.861253468695;5805.809808989147;6824.897626535606;7047.119110111236;7069.399101076466;7078.893816592756;1591.1248813152952;1601.0793793568744;1615.1439736939442;1618;1619.3846338391572;923.2475532441173;968.5399435268332;994.0010289658846;1021.7496713305288;1049.7060822969581;6285.402886725754;6407.615543067431;6432.292447830735;6491.415772580491;6519.119918810995;5101.846944591594;6259.812205633838;7106.5736055836815;7117.537360071682;7378.889316660255;6683.6324002180345;6693;6701.229358623723;6704.83237919056;6710.530262881552;3193.9041828745176;3839.0210496323257;3871.915863558208;3890.280298144852;3901.5318161820765;7400.074857671859;7604.80987975294;7710.884336734954;7743.690252389842;7750.7674769756995;6170.321264660884;6464.806055818333;7349.375303099297;7571.734989275349;7615.390768738488;7674.884876726854;7679;7682.615869206629;7690.230976902377;7700.229977002367;7319.853602927805;7329.120505539369;7336.303051210127;7342.412606991535;7369;6919.37725604698;7200.927990720026;7267;7281.817954551178;7299.489035767445;6304.6847657617855;7676;7708.961455192688;7725.420593455401;7740.1098873628;4585.747783871919;4615.169269531517;4621.5904149233465;4629.745364004877;4643.419572553431;7291.30732580404;7305.561666300039;7315.817104572428;7317.938898859529;7318.707251709872;6777.6272305023695;7049.612271325126;7077.469189810888;7079.787606371817;7105.786826395217;7001.544899581511;7038.190608080136;7040.225575186828;7053.329933656294;7062.611556364449;6864.347886950732;6889.689963951734;6895.138107736533;6905.274946130584;6905.482088843256;6789.1852977643;7034.155901291673;7040.718371265092;7072.575645461289;7075.575465472089;6373.713182906873;6663.967085101623;6812.65936703153;6820.658967051727;6955.547889387173 22:29:17 INFO - PROCESS | 2245 | |5;yui.html;623.8066199478164;695.9965200173998;701.6526819224484;731.9048523691919;747.4730315127836;14736.410543578257;14918.776218356734;14938.925305373485;14944.252787360616;14968.77546836797;13370.331483425814;13584.728305433886;13594.388252528635;13665.043446958716;13990.16059036456;847.5338563789921;848.9702860399888;857.6140736668493;857.7169534053778;860.1656393298522;730.6164263761541;838.8993320801512;874.3704532736451;876.4828751036887;877.4559772940786;897.8563429851225;917.3257655623112;921.0237148622482;923.224491427201;924.2421214604027;894.9642014319419;914.7804526913559;916.4363916191561;917.6191880369638;918.7978644698155;659.8713250916073;664.2162248546713;674.6559254780049;675.746595026865;676.1649363036695;12935.223886566837;12951.740965180737;12977;12977.41601627929;13013;11047.171462140372;11218.775624487545;11225.326480411202;11338;11377;8390.916090839075;9140.994490606028;9262.907370926272;9427.387219830689;9450.52747362629;2999.925001874949;3426.3318652862704;3468.757186996911;3528.6118526962014;3535.222251104753;80590;81163;81289.96775080611;81482.3703525932;81550;8046.681516252291;8286.917130828735;8287.295579875778;8348.958255208685;8683.305335573265;18801.623967520572;18856.52858678544;18857.528561786065;18897.622047559245;18926.43220703381;7295.245744770543;7421.3297172257135;7816.804579885548;7817.335526480313;7832.412569057344;3943.546492153439;3989.3018721723583;4002.3596224603925;4024.6981476389387;4087.8773636790943;2963.955540666892;3436.759426840147;3518.4546395308766;3518.5601799775027;3521.454174602941;611.5382885921101;615.0743131586933;617.4998251416367;621.9129321894939;628.1425853709744;479.4917387569188;570.1419363857368;576.230731972821;596.2249076200922;607.9209702738705;7960.800979975547;7975.561344126013;8095.55474448911;8184.345252379795;8319.42931931939;565.3329071695439;568.8492549474392;580.1095318685881;595.3718826637938;598.9460948514654;3689.797061161662;3692.8522859085874;3706;3782.659560639556;3932.2921874062945;93940.53029734807;94457.52771236237;96076.51961740147;96093.0390696084;96249;58870.41129588649;80416.59791701005;80417.59791201123;80609;83050.58474707708;67374;67841.99999999901;68199.65900170467;68678.62642747218;68691.65654171698;8067.314278286177;8174.427790054639;8240.258376746122;8277.461964972257;8350.665973361241;44320.77839610782;45257.547424525335;50751.492485074676;51209.48790512196;52072.73963630158;61135;70391.64804175946;71023.28976710167;72356;73066;11181.888181118085;11510.769784604428;11529.711757206136;11812;12192.939035305122;10388.844167337647;11644.25336199543;12008.159428840247;12197.573084941985;12314;11208.607698730506;11218.607348742755;11332.716682082684;11478.655640330804;11683;11035;11044.447777611247;11340.979311861973;11452;11734.480075030322;10448.686539403527;10526.631537900024;10836.620718274822;10893.836592451278;10911.399873007062;9013.233875120688;9094.954525227597;9102.499362535124;9402.059794020817;9441.716748497282;8585.742427727178;8801;8863.49144062413;9296.953515232652;9307.72076837696 22:29:17 INFO - PROCESS | 2245 | __end_tp_report 22:29:17 INFO - PROCESS | 2245 | __start_cc_report 22:29:17 INFO - PROCESS | 2245 | _x_x_mozilla_cycle_collect,1145 22:29:17 INFO - PROCESS | 2245 | __end_cc_report 22:29:17 INFO - PROCESS | 2245 | __startTimestamp1457072957751__endTimestamp 22:29:17 INFO - PROCESS | 2245 | 22:29:17 INFO - PROCESS | 2245 | ------- Summary: start ------- 22:29:17 INFO - PROCESS | 2245 | Number of tests: 6 22:29:17 INFO - PROCESS | 2245 | 22:29:17 INFO - PROCESS | 2245 | [#0] dojo.html Cycles:165 Average:5666.54 Median:5850.30 stddev:640.93 (11.0%) stddev-sans-first:611.27 22:29:17 INFO - PROCESS | 2245 | Values: 3132.0 3410.9 3441.7 3463.1 3496.2 5514.6 5563.8 5584.6 5600.3 5633.4 5627.0 5630.4 5677.0 5742.8 5754.4 5008.3 5039.2 5058.2 5089.3 5202.3 5125.9 5148.7 5171.6 5209.7 5240.3 4987.9 4994.5 5065.1 5092.2 5101.3 5109.5 5120.6 5129.5 5136.5 5140.7 5016.2 5065.4 5080.5 5090.9 5095.4 5428.0 5530.5 5535.3 5567.2 5598.7 5122.8 6135.5 6155.7 6157.0 6167.5 5390.9 6150.4 6228.2 6233.4 6258.7 5573.1 5684.4 5695.5 5704.9 5775.2 6265.3 6294.0 6317.2 6319.7 6325.7 5655.4 5694.1 6016.9 6109.0 6116.1 6083.0 6136.0 6138.4 6215.8 6247.3 6138.2 6142.5 6148.6 6162.3 6163.7 6162.3 6164.9 6180.1 6184.9 6188.1 4727.8 4800.4 4825.5 4846.2 4895.0 4350.2 4353.2 4371.5 4379.1 4393.7 5578.4 5649.2 5666.1 5672.9 5679.4 6108.1 6131.3 6235.0 6247.3 6271.9 5649.9 5653.9 5667.7 5682.4 5737.2 5296.8 5313.0 5323.5 5325.2 5329.9 6192.7 6201.3 6282.7 6283.5 6289.7 6227.9 6254.4 6256.0 6265.9 6287.8 6257.2 6265.1 6273.6 6274.3 6286.6 5996.5 6022.0 6111.4 6136.1 6155.8 6175.6 6246.2 6251.2 6253.6 6254.7 6068.8 6069.6 6073.4 6073.9 6085.0 5850.3 5966.4 6000.8 6025.4 6121.9 5837.2 5851.0 5856.5 5859.2 5893.1 5929.9 5987.8 6005.7 6008.9 6016.4 5746.1 5835.2 5874.4 5880.2 5945.1 22:29:17 INFO - PROCESS | 2245 | 22:29:17 INFO - PROCESS | 2245 | [#1] ext.html Cycles:180 Average:61266.43 Median:381513.05 stddev:126146.81 (33.1%) stddev-sans-first:126418.09 22:29:17 INFO - PROCESS | 2245 | Values: 467.1 571.0 571.4 622.7 633.4 34988.6 35953.3 38459.0 38836.4 38965.8 34561.2 41667.8 42177.8 42251.8 42284.0 9507.7 9764.7 9764.9 9774.3 9789.2 9576.4 9743.6 9747.1 9753.5 9757.1 9764.0 9879.4 9914.6 9924.6 9993.0 9767.7 9882.4 9883.0 9889.4 9894.8 9584.3 9734.8 9735.0 9743.4 9744.8 40317.6 40671.0 40676.4 40689.6 40749.8 2609.2 3156.6 3181.2 3256.1 3419.6 28411.4 28473.0 28753.0 28878.1 28912.7 2711.8 2756.9 2764.0 2766.2 2838.4 489158.0 490893.0 491231.0 491582.0 493296.0 53315.3 63967.2 65055.0 65127.7 65137.7 69658.0 71755.6 72029.6 72168.0 72218.0 12670.6 13256.3 14588.0 15047.4 15202.5 11420.0 11450.0 11462.8 11472.5 11474.3 20640.7 20967.2 21031.8 21054.0 21553.5 8696.2 8962.3 8963.1 8968.1 9070.1 958.6 963.5 1072.7 1090.3 1171.7 28571.3 28677.0 29180.9 29250.3 29282.9 968.4 1034.2 1040.0 1048.3 1185.8 14345.6 14375.2 14456.7 14762.7 14794.0 528457.0 536381.0 536772.0 537423.0 537444.0 46931.1 53786.0 56215.4 56216.7 56248.6 1251.5 1279.9 1374.1 1383.2 1408.3 27930.4 28253.2 28772.8 29474.9 29488.6 46137.8 46554.1 46826.3 46876.0 47025.8 380627.1 382399.0 383166.0 383186.0 383983.1 28202.7 31738.7 32739.8 32750.0 32788.3 39968.8 40311.0 40500.0 40569.4 41434.0 33238.8 34384.8 34780.7 34884.1 35030.1 33161.8 33558.0 34014.1 34307.0 35585.0 28760.4 34136.0 34465.7 34514.0 35532.8 30372.9 30646.2 30827.5 30958.8 30963.2 19037.0 22607.2 23144.0 24011.3 24468.5 22:29:17 INFO - PROCESS | 2245 | 22:29:17 INFO - PROCESS | 2245 | [#2] jquery.html Cycles:180 Average:35752.94 Median:24139.84 stddev:107612.94 (445.8%) stddev-sans-first:107881.94 22:29:17 INFO - PROCESS | 2245 | Values: 321.2 351.1 355.7 359.5 362.8 19294.8 19443.9 20777.7 20784.6 20795.0 17930.8 18327.4 18542.2 18552.5 18583.0 5522.9 5773.9 5843.2 5846.1 5864.0 4171.9 4355.1 4366.2 4370.0 4833.4 4462.4 6341.4 6344.8 6351.1 6356.9 5944.9 6292.2 6314.5 6343.7 6349.0 3772.7 5441.9 5458.1 5461.2 5467.5 23094.7 23152.9 24089.4 24110.6 24169.0 2059.8 2201.8 2314.6 2583.5 2728.2 9736.4 10047.9 11898.1 12246.5 14707.6 1677.3 1686.5 1705.9 1734.2 1783.1 108452.0 111056.0 112350.0 113530.4 113852.4 10985.0 11007.9 11229.4 11245.9 11350.8 13541.4 13673.0 13688.7 13702.9 13712.7 7864.6 8046.0 8053.9 8127.9 8132.5 7955.6 8000.2 8030.9 8081.0 8083.9 4438.4 4469.4 4471.8 4485.2 4521.5 1707.3 1810.2 1862.9 1894.2 1900.1 845.0 925.0 927.9 956.9 991.5 14405.9 19743.2 20046.9 20100.9 20462.0 795.4 940.0 958.3 961.6 1019.5 10281.8 10581.6 10617.0 10633.7 10651.0 639174.0 646840.0 651350.0 652457.0 655361.7 102527.0 120339.2 122966.2 126255.4 127239.4 942.9 950.5 961.7 963.2 997.5 17183.3 17450.9 17609.7 17855.6 17990.7 51300.5 51803.5 52834.7 52923.5 54653.0 76103.6 76564.9 77188.6 77227.6 77434.0 6904.6 7689.3 7868.1 7981.6 8127.5 9449.6 12312.6 12920.0 12929.1 13286.0 11235.4 11508.7 11559.5 11574.1 11596.5 10699.0 11012.7 11150.0 11453.8 11513.8 11517.1 11547.4 11703.1 11874.9 11897.0 6877.6 7831.7 7882.8 8137.5 8201.5 11291.5 11305.8 11593.8 11628.7 11697.0 22:29:17 INFO - PROCESS | 2245 | 22:29:17 INFO - PROCESS | 2245 | [#3] mootools.html Cycles:180 Average:5976.81 Median:2911.85 stddev:3201.01 (109.9%) stddev-sans-first:3191.40 22:29:17 INFO - PROCESS | 2245 | Values: 1386.8 1519.1 1525.6 1756.6 1933.9 7822.4 8445.7 8593.5 8598.5 8600.9 8352.0 8588.4 8590.7 8604.5 8617.9 2140.2 2385.6 2430.8 2455.2 2457.2 2462.6 2467.7 2471.1 2472.8 2474.6 2302.0 2306.8 2353.1 2428.4 2443.7 2402.9 2444.9 2445.7 2452.5 2464.1 2427.1 2433.8 2453.5 2455.3 2462.0 8674.9 8725.5 8754.1 8769.6 8770.8 5176.2 5300.6 5321.5 5331.0 5332.6 3020.4 3514.1 3664.8 3665.2 3685.0 1029.7 1056.7 1060.5 1072.1 1072.3 12001.5 12083.6 12087.8 12090.9 12113.6 4757.1 4808.6 4835.3 4835.8 4880.8 10132.1 10142.4 10145.6 10156.6 10163.3 5304.1 5306.6 5313.0 5316.3 5321.8 4310.0 4452.4 4658.2 4764.9 4789.1 3868.7 3928.1 3938.5 3963.7 3976.8 1287.0 1297.2 1299.3 1308.7 1310.7 1120.1 1140.4 1141.5 1367.8 1368.7 8150.0 8215.0 8257.4 8261.8 8266.9 505.0 516.4 517.6 518.4 523.1 6171.5 6171.8 6226.7 6231.3 6237.6 10289.2 10629.8 10775.7 10808.3 10817.7 9966.9 10547.8 10555.1 10647.6 10683.8 7994.0 8148.0 8180.6 8218.6 8227.1 7917.8 7929.1 7998.1 8032.0 8054.9 9184.9 9279.6 9396.8 9449.9 9560.7 9712.8 9794.5 9811.6 9862.7 10068.4 6801.4 6984.3 7217.9 7499.8 7626.9 8153.3 8180.5 8195.7 8233.9 8239.3 7158.4 7288.5 7314.4 7404.2 7454.1 6038.5 7125.5 7147.2 7230.9 7282.0 6477.0 7613.5 7668.3 7675.7 7677.0 6366.0 6659.8 6909.4 7018.4 7030.3 6955.3 7074.3 7080.2 7104.8 7108.9 22:29:17 INFO - PROCESS | 2245 | 22:29:17 INFO - PROCESS | 2245 | [#4] prototype.html Cycles:180 Average:5711.92 Median:6519.36 stddev:1848.05 (28.3%) stddev-sans-first:1808.27 22:29:17 INFO - PROCESS | 2245 | Values: 313.2 317.3 322.9 327.6 331.0 6489.4 6571.5 6576.0 6598.0 6633.3 6423.3 6506.7 6531.0 6553.5 6585.5 4585.9 4710.5 4740.2 4849.9 4874.8 4795.8 4808.8 4810.4 4814.2 4822.2 4718.2 4740.6 4750.5 4774.7 4782.5 3888.5 4572.7 4662.2 4817.1 4854.8 4104.3 4106.7 4111.2 4113.8 4114.3 6303.7 6313.6 6367.0 6519.6 6523.3 5628.4 5633.7 5634.6 5642.3 5664.8 5635.7 6839.0 6945.3 6962.5 6972.9 4314.4 4504.6 4529.0 4542.6 4600.3 7382.0 7394.4 7457.8 7469.4 7478.4 4601.1 4605.8 4607.5 4608.8 4609.5 4656.3 4660.1 4669.0 4669.2 4671.5 5546.6 5548.8 5549.4 5549.5 5549.9 5805.8 6824.9 7047.1 7069.4 7078.9 1591.1 1601.1 1615.1 1618.0 1619.4 923.2 968.5 994.0 1021.7 1049.7 6285.4 6407.6 6432.3 6491.4 6519.1 5101.8 6259.8 7106.6 7117.5 7378.9 6683.6 6693.0 6701.2 6704.8 6710.5 3193.9 3839.0 3871.9 3890.3 3901.5 7400.1 7604.8 7710.9 7743.7 7750.8 6170.3 6464.8 7349.4 7571.7 7615.4 7674.9 7679.0 7682.6 7690.2 7700.2 7319.9 7329.1 7336.3 7342.4 7369.0 6919.4 7200.9 7267.0 7281.8 7299.5 6304.7 7676.0 7709.0 7725.4 7740.1 4585.7 4615.2 4621.6 4629.7 4643.4 7291.3 7305.6 7315.8 7317.9 7318.7 6777.6 7049.6 7077.5 7079.8 7105.8 7001.5 7038.2 7040.2 7053.3 7062.6 6864.3 6889.7 6895.1 6905.3 6905.5 6789.2 7034.2 7040.7 7072.6 7075.6 6373.7 6664.0 6812.7 6820.7 6955.5 22:29:17 INFO - PROCESS | 2245 | 22:29:17 INFO - PROCESS | 2245 | [#5] yui.html Cycles:180 Average:18026.66 Median:622.86 stddev:25955.18 (4167.1%) stddev-sans-first:25995.09 22:29:17 INFO - PROCESS | 2245 | Values: 623.8 696.0 701.7 731.9 747.5 14736.4 14918.8 14938.9 14944.3 14968.8 13370.3 13584.7 13594.4 13665.0 13990.2 847.5 849.0 857.6 857.7 860.2 730.6 838.9 874.4 876.5 877.5 897.9 917.3 921.0 923.2 924.2 895.0 914.8 916.4 917.6 918.8 659.9 664.2 674.7 675.7 676.2 12935.2 12951.7 12977.0 12977.4 13013.0 11047.2 11218.8 11225.3 11338.0 11377.0 8390.9 9141.0 9262.9 9427.4 9450.5 2999.9 3426.3 3468.8 3528.6 3535.2 80590.0 81163.0 81290.0 81482.4 81550.0 8046.7 8286.9 8287.3 8349.0 8683.3 18801.6 18856.5 18857.5 18897.6 18926.4 7295.2 7421.3 7816.8 7817.3 7832.4 3943.5 3989.3 4002.4 4024.7 4087.9 2964.0 3436.8 3518.5 3518.6 3521.5 611.5 615.1 617.5 621.9 628.1 479.5 570.1 576.2 596.2 607.9 7960.8 7975.6 8095.6 8184.3 8319.4 565.3 568.8 580.1 595.4 598.9 3689.8 3692.9 3706.0 3782.7 3932.3 93940.5 94457.5 96076.5 96093.0 96249.0 58870.4 80416.6 80417.6 80609.0 83050.6 67374.0 67842.0 68199.7 68678.6 68691.7 8067.3 8174.4 8240.3 8277.5 8350.7 44320.8 45257.5 50751.5 51209.5 52072.7 61135.0 70391.6 71023.3 72356.0 73066.0 11181.9 11510.8 11529.7 11812.0 12192.9 10388.8 11644.3 12008.2 12197.6 12314.0 11208.6 11218.6 11332.7 11478.7 11683.0 11035.0 11044.4 11341.0 11452.0 11734.5 10448.7 10526.6 10836.6 10893.8 10911.4 9013.2 9095.0 9102.5 9402.1 9441.7 8585.7 8801.0 8863.5 9297.0 9307.7 22:29:17 INFO - PROCESS | 2245 | -------- Summary: end -------- 22:29:17 INFO - PROCESS | 2245 | 22:29:18 INFO - TEST-INFO | 2245: exit 0 22:29:18 INFO - TEST-OK | dromaeo_css | took 1112767ms 22:29:18 INFO - TEST-START | kraken 22:29:18 INFO - operating with platform_type : mac_ 22:29:18 INFO - Initialising browser for kraken test... 22:29:18 INFO - TEST-INFO | started process 2247 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpkWqbvy/profile http://localhost:50088/getInfo.html) 22:29:20 INFO - PROCESS | 2247 | __metrics Screen width/height:1600/1200 22:29:20 INFO - PROCESS | 2247 | colorDepth:24 22:29:20 INFO - PROCESS | 2247 | Browser inner width/height: 1024/689 22:29:20 INFO - PROCESS | 2247 | __metrics 22:29:20 INFO - PROCESS | 2247 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:29:20 INFO - PROCESS | 2247 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:29:21 INFO - TEST-INFO | 2247: exit 0 22:29:21 INFO - Browser initialized. 22:29:21 INFO - Running cycle 1/1 for kraken test... 22:29:21 INFO - TEST-INFO | started process 2249 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpkWqbvy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:29:32 INFO - PROCESS | 2249 | RSS: Main: 243556352 22:29:32 INFO - PROCESS | 2249 | 22:29:35 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50088/tests/kraken/driver.html?testName=audio-beat-detection) 22:29:36 INFO - PROCESS | 2249 | RSS: Main: 289529856 22:29:36 INFO - PROCESS | 2249 | 22:29:38 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50088/tests/kraken/driver.html?testName=audio-dft) 22:29:39 INFO - PROCESS | 2249 | RSS: Main: 298274816 22:29:39 INFO - PROCESS | 2249 | 22:29:42 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50088/tests/kraken/driver.html?testName=audio-fft) 22:29:43 INFO - PROCESS | 2249 | RSS: Main: 286474240 22:29:43 INFO - PROCESS | 2249 | 22:29:45 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50088/tests/kraken/driver.html?testName=audio-oscillator) 22:29:45 INFO - PROCESS | 2249 | RSS: Main: 287813632 22:29:45 INFO - PROCESS | 2249 | 22:29:47 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50088/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:29:48 INFO - PROCESS | 2249 | RSS: Main: 290041856 22:29:48 INFO - PROCESS | 2249 | 22:29:51 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50088/tests/kraken/driver.html?testName=imaging-darkroom) 22:29:52 INFO - PROCESS | 2249 | RSS: Main: 290611200 22:29:52 INFO - PROCESS | 2249 | 22:29:54 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50088/tests/kraken/driver.html?testName=imaging-desaturate) 22:29:55 INFO - PROCESS | 2249 | RSS: Main: 290611200 22:29:55 INFO - PROCESS | 2249 | 22:29:58 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50088/tests/kraken/driver.html?testName=json-parse-financial) 22:29:58 INFO - PROCESS | 2249 | RSS: Main: 289320960 22:29:58 INFO - PROCESS | 2249 | 22:30:00 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50088/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:30:00 INFO - PROCESS | 2249 | RSS: Main: 322371584 22:30:00 INFO - PROCESS | 2249 | 22:30:02 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50088/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:30:03 INFO - PROCESS | 2249 | RSS: Main: 321818624 22:30:03 INFO - PROCESS | 2249 | 22:30:05 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50088/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:30:06 INFO - PROCESS | 2249 | RSS: Main: 328019968 22:30:06 INFO - PROCESS | 2249 | 22:30:08 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50088/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:30:09 INFO - PROCESS | 2249 | RSS: Main: 331071488 22:30:09 INFO - PROCESS | 2249 | 22:30:11 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50088/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:30:12 INFO - PROCESS | 2249 | RSS: Main: 331038720 22:30:12 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | Cycle 1(1): loaded http://localhost:50088/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50088/tests/kraken/driver.html?testName=ai-astar) 22:30:14 INFO - PROCESS | 2249 | RSS: Main: 391208960 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | __start_tp_report 22:30:14 INFO - PROCESS | 2249 | _x_x_mozilla_page_load 22:30:14 INFO - PROCESS | 2249 | _x_x_mozilla_page_load_details 22:30:14 INFO - PROCESS | 2249 | |i|pagename|runs| 22:30:14 INFO - PROCESS | 2249 | |0;ai-astar;112;109;159;88;152;164;166;110;110;111 22:30:14 INFO - PROCESS | 2249 | |1;audio-beat-detection;161;121;144;159;119;141;141;120;161;122 22:30:14 INFO - PROCESS | 2249 | |2;audio-dft;185;184;185;181;186;183;185;181;182;182 22:30:14 INFO - PROCESS | 2249 | |3;audio-fft;79;75;87;79;56;57;56;79;83;55 22:30:14 INFO - PROCESS | 2249 | |4;audio-oscillator;101;90;132;80;77;87;110;76;76;110 22:30:14 INFO - PROCESS | 2249 | |5;imaging-gaussian-blur;89;87;135;86;92;131;120;86;89;85 22:30:14 INFO - PROCESS | 2249 | |6;imaging-darkroom;93;93;94;94;93;94;94;94;94;93 22:30:14 INFO - PROCESS | 2249 | |7;imaging-desaturate;85;84;86;86;85;86;87;88;84;85 22:30:14 INFO - PROCESS | 2249 | |8;json-parse-financial;56;56;56;54;53;53;55;66;56;51 22:30:14 INFO - PROCESS | 2249 | |9;json-stringify-tinderbox;66;69;69;74;72;68;70;67;66;67 22:30:14 INFO - PROCESS | 2249 | |10;stanford-crypto-aes;100;106;108;111;105;81;82;83;85;82 22:30:14 INFO - PROCESS | 2249 | |11;stanford-crypto-ccm;130;124;128;105;116;131;131;135;116;121 22:30:14 INFO - PROCESS | 2249 | |12;stanford-crypto-pbkdf2;139;126;138;137;139;127;139;136;169;175 22:30:14 INFO - PROCESS | 2249 | |13;stanford-crypto-sha256-iterative;66;66;65;66;66;66;66;66;75;97 22:30:14 INFO - PROCESS | 2249 | __end_tp_report 22:30:14 INFO - PROCESS | 2249 | __start_cc_report 22:30:14 INFO - PROCESS | 2249 | _x_x_mozilla_cycle_collect,1199 22:30:14 INFO - PROCESS | 2249 | __end_cc_report 22:30:14 INFO - PROCESS | 2249 | __startTimestamp1457073014332__endTimestamp 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | ------- Summary: start ------- 22:30:14 INFO - PROCESS | 2249 | Number of tests: 14 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#0] ai-astar Cycles:10 Average:128.10 Median:155.50 stddev:28.73 (18.5%) stddev-sans-first:29.88 22:30:14 INFO - PROCESS | 2249 | Values: 112.0 109.0 159.0 88.0 152.0 164.0 166.0 110.0 110.0 111.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#1] audio-beat-detection Cycles:10 Average:138.90 Median:142.50 stddev:17.56 (12.3%) stddev-sans-first:16.70 22:30:14 INFO - PROCESS | 2249 | Values: 161.0 121.0 144.0 159.0 119.0 141.0 141.0 120.0 161.0 122.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.50 stddev:1.84 (1.0%) stddev-sans-first:1.86 22:30:14 INFO - PROCESS | 2249 | Values: 185.0 184.0 185.0 181.0 186.0 183.0 185.0 181.0 182.0 182.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#3] audio-fft Cycles:10 Average:70.60 Median:79.00 stddev:12.95 (16.4%) stddev-sans-first:13.37 22:30:14 INFO - PROCESS | 2249 | Values: 79.0 75.0 87.0 79.0 56.0 57.0 56.0 79.0 83.0 55.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:76.50 stddev:18.86 (24.7%) stddev-sans-first:19.83 22:30:14 INFO - PROCESS | 2249 | Values: 101.0 90.0 132.0 80.0 77.0 87.0 110.0 76.0 76.0 110.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#5] imaging-gaussian-blur Cycles:10 Average:100.00 Median:86.50 stddev:20.22 (23.4%) stddev-sans-first:21.05 22:30:14 INFO - PROCESS | 2249 | Values: 89.0 87.0 135.0 86.0 92.0 131.0 120.0 86.0 89.0 85.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 22:30:14 INFO - PROCESS | 2249 | Values: 93.0 93.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 93.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.26 (1.5%) stddev-sans-first:1.32 22:30:14 INFO - PROCESS | 2249 | Values: 85.0 84.0 86.0 86.0 85.0 86.0 87.0 88.0 84.0 85.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:4.03 (7.2%) stddev-sans-first:4.28 22:30:14 INFO - PROCESS | 2249 | Values: 56.0 56.0 56.0 54.0 53.0 53.0 55.0 66.0 56.0 51.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:69.00 stddev:2.62 (3.8%) stddev-sans-first:2.57 22:30:14 INFO - PROCESS | 2249 | Values: 66.0 69.0 69.0 74.0 72.0 68.0 70.0 67.0 66.0 67.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#10] stanford-crypto-aes Cycles:10 Average:94.30 Median:81.50 stddev:12.67 (15.5%) stddev-sans-first:13.27 22:30:14 INFO - PROCESS | 2249 | Values: 100.0 106.0 108.0 111.0 105.0 81.0 82.0 83.0 85.0 82.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:129.00 stddev:9.24 (7.2%) stddev-sans-first:9.51 22:30:14 INFO - PROCESS | 2249 | Values: 130.0 124.0 128.0 105.0 116.0 131.0 131.0 135.0 116.0 121.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.50 Median:139.00 stddev:16.33 (11.7%) stddev-sans-first:17.27 22:30:14 INFO - PROCESS | 2249 | Values: 139.0 126.0 138.0 137.0 139.0 127.0 139.0 136.0 169.0 175.0 22:30:14 INFO - PROCESS | 2249 | 22:30:14 INFO - PROCESS | 2249 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:66.00 stddev:9.95 (15.1%) stddev-sans-first:10.45 22:30:14 INFO - PROCESS | 2249 | Values: 66.0 66.0 65.0 66.0 66.0 66.0 66.0 66.0 75.0 97.0 22:30:14 INFO - PROCESS | 2249 | -------- Summary: end -------- 22:30:14 INFO - PROCESS | 2249 | 22:30:15 INFO - TEST-INFO | 2249: exit 0 22:30:15 INFO - TEST-OK | kraken | took 56772ms 22:30:15 INFO - SUITE-END | took 1169s 22:30:15 INFO - Completed test suite (00:19:29) 22:30:15 INFO - Working with test: dromaeo_css 22:30:15 INFO - Generating results file: dromaeo_css 22:30:15 INFO - Working with test: kraken 22:30:15 INFO - Generating results file: kraken 22:30:15 INFO - javascript benchmark 22:30:15 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:30:15 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 22:30:15 INFO - process_Request line: dromaeo_css 7395.51 graph.html#tests=[[72,64,59]] 22:30:15 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:30:16 INFO - process_Request line: kraken 1448.90 graph.html#tests=[[232,64,59]] 22:30:16 INFO - RETURN: dromaeo_css: 7395.51 22:30:16 INFO - RETURN: kraken: 1448.90 22:30:16 INFO - javascript benchmark 22:30:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3131.9843400782993, 3410.880619178329, 3441.7074548663363, 3463.1342164458883, 3496.178398076453, 5514.641548299358, 5563.805266815662, 5584.58115641327, 5600.27196464459, 5633.408492108326, 5626.971865140678, 5630.352509461413, 5677.0, 5742.79900203493, 5754.367019627837, 5008.3238762767105, 5039.168537191368, 5058.190689489682, 5089.287499750038, 5202.271681964528, 5125.871853203663, 5148.6910785353, 5171.612129090315, 5209.687418754868, 5240.318758561381, 4987.900241995158, 4994.475580064077, 5065.088284108859, 5092.185250359925, 5101.260317253996, 5109.5145961134, 5120.564751996085, 5129.5383415492415, 5136.486351364871, 5140.691558506502, 5016.247562865563, 5065.39215294167, 5080.466551012128, 5090.9236361454605, 5095.414027386824, 5428.0, 5530.474604912567, 5535.280413546254, 5567.192757050205, 5598.692071936042, 5122.796142906414, 6135.477655820245, 6155.692215389213, 6157.0, 6167.537434692371, 5390.948764990829, 6150.384961503859, 6228.159198508234, 6233.407826256544, 6258.687065646699, 5573.108302671593, 5684.37471878093, 5695.530071381195, 5704.942950570441, 5775.16260142277, 6265.279492858289, 6293.968530157412, 6317.210348706411, 6319.652419116901, 6325.746970121144, 5655.4344565543115, 5694.1458781183155, 6016.909746353808, 6109.0, 6116.143739876421, 6083.0, 6136.048912418582, 6138.355472675305, 6215.844603884849, 6247.281562620356, 6138.202033735586, 6142.529470848956, 6148.631082135086, 6162.291336496271, 6163.722632481549, 6162.322144564095, 6164.938350616526, 6180.103884936659, 6184.938150618526, 6188.133661287423, 4727.787249573777, 4800.35195248644, 4825.4933232010835, 4846.200376937836, 4895.020995800783, 4350.151720414488, 4353.194658988097, 4371.540988196193, 4379.1241751649795, 4393.670474714345, 5578.3584887738425, 5649.180868774005, 5666.065099258659, 5672.886542269214, 5679.4320567943705, 6108.053251745987, 6131.284916201153, 6235.0, 6247.312795592481, 6271.937280627227, 5649.943500565024, 5653.858653533613, 5667.688277144796, 5682.374938756733, 5737.168110624019, 5296.788128474818, 5313.017091837945, 5323.494268044529, 5325.174597937326, 5329.9467005330225, 6192.690365481798, 6201.2868520118955, 6282.748690052346, 6283.465905398092, 6289.685515724104, 6227.875442491216, 6254.374562543891, 6256.0, 6265.874682506415, 6287.811365659038, 6257.217847769029, 6265.122882796321, 6273.592216505913, 6274.309825919054, 6286.622802631857, 5996.4603185713495, 6022.0, 6111.419415155553, 6136.079588061826, 6155.815325540241, 6175.62946223228, 6246.156768836149, 6251.156093927261, 6253.624782513064, 6254.718537665907, 6068.787592434243, 6069.635821850703, 6073.362296958756, 6073.908891366721, 6085.0, 5850.297964244318, 5966.373530779206, 6000.819975400745, 6025.386296976899, 6121.938780612137, 5837.153612726046, 5851.0, 5856.531477481706, 5859.150423188699, 5893.145493903275, 5929.940700593112, 5987.760489580368, 6005.729742161526, 6008.909866351921, 6016.42843929826, 5746.13807928814, 5835.2122463466885, 5874.441928016831, 5880.206172166702, 5945.078512830518], "unit": "score", "name": "dojo.html", "value": 5625.534635682023}, {"lowerIsBetter": false, "replicates": [467.1381301498738, 570.9551520717085, 571.362930332679, 622.701103470334, 633.3571425003622, 34988.60045598176, 35953.2809343813, 38459.0, 38836.41745373821, 38965.80517097418, 34561.23510119191, 41667.79166104165, 42177.7891110544, 42251.788741056254, 42284.0, 9507.667231646876, 9764.658236961708, 9764.925858155597, 9774.315797894114, 9789.167920726746, 9576.377535460173, 9743.610255589803, 9747.074027967332, 9753.463559504224, 9757.073078057572, 9764.0, 9879.407235565854, 9914.55384507699, 9924.603015879356, 9993.0, 9767.658131965383, 9882.407055576688, 9883.01169883006, 9889.406635601887, 9894.80210395788, 9584.329096963216, 9734.805303893952, 9734.951325243328, 9743.415395076247, 9744.75638109053, 40317.59682403168, 40671.0, 40676.389854152214, 40689.593104068874, 40749.79625101855, 2609.2041927212194, 3156.589643346373, 3181.1569933967517, 3256.0882952773263, 3419.6238413774463, 28411.431771364456, 28473.0, 28753.0, 28878.133655990354, 28912.71087289142, 2711.7830573554265, 2756.944861102787, 2763.958540621892, 2766.225456872079, 2838.3613686920485, 489158.0, 490893.0, 491231.0, 491582.0, 493296.0, 53315.33558305212, 63967.161970281, 65055.0, 65127.67436162789, 65137.67431162909, 69658.0, 71755.64122179356, 72029.63985180145, 72168.0, 72218.0, 12670.619881403572, 13256.337183140804, 14588.0, 15047.398104075934, 15202.46791362297, 11420.029297509638, 11450.026747726537, 11462.770744585061, 11472.541098355972, 11474.254173478697, 20640.69038964417, 20967.161313547593, 21031.789682102983, 21054.0, 21553.461163470725, 8696.17386348296, 8962.32782541312, 8963.078285177093, 8968.148025937526, 9070.092990701009, 958.5542722633869, 963.5326866469757, 1072.7210925159359, 1090.2858627598935, 1171.654361963206, 28571.28571785722, 28677.0, 29180.854095729384, 29250.268743281587, 29282.853585731937, 968.4334664221368, 1034.234666346913, 1039.9584016639249, 1048.3290693955873, 1185.8141858141857, 14345.6413589659, 14375.209363485108, 14456.710865782625, 14762.74516666074, 14794.0, 528457.0, 536381.0, 536772.0, 537423.0, 537444.0, 46931.06137877223, 53786.0, 56215.37846215346, 56216.718916405975, 56248.593785155696, 1251.4681260464267, 1279.8848103670714, 1374.0519041861085, 1383.204657322064, 1408.3028900694221, 27930.44139117247, 28253.15240542787, 28772.84908603716, 29474.852625736734, 29488.55767163537, 46137.769311154574, 46554.13783448624, 46826.29760553526, 46876.0, 47025.824354390046, 380627.0968645139, 382399.0, 383166.0, 383186.0, 383983.0800845978, 28202.73087711017, 31738.682613173572, 32739.836300818344, 32750.0, 32788.344233115684, 39968.80015599904, 40311.0, 40500.0, 40569.391459128725, 41433.99207115012, 33238.833805830815, 34384.82807585946, 34780.65219347875, 34884.127896802784, 35030.124246893014, 33161.83419082889, 33558.0, 34014.149646259044, 34307.0, 35585.0, 28760.424791504473, 34136.0, 34465.65534344625, 34514.0, 35532.82233588816, 30372.93694720674, 30646.233844154074, 30827.537586935767, 30958.84520577383, 30963.225919352197, 19037.04814759229, 22607.227760352882, 23144.0, 24011.27966161018, 24468.51062978766], "unit": "score", "name": "ext.html", "value": 19248.101809896245}, {"lowerIsBetter": false, "replicates": [321.23706197780274, 351.1327022255029, 355.7100962715387, 359.47875580408396, 362.80952499937547, 19294.807051929478, 19443.930583817884, 20777.688334674993, 20784.584308313824, 20794.99999999998, 17930.820691793077, 18327.358542451017, 18542.165602547884, 18552.536186595342, 18582.977936213538, 5522.889542209164, 5773.913391299134, 5843.240378750777, 5846.09385545239, 5864.0, 4171.937420938689, 4355.063661312817, 4366.170427618742, 4369.97305633176, 4833.371661683994, 4462.3975763271865, 6341.429271365576, 6344.777932772354, 6351.079093531435, 6356.936430635704, 5944.910826337608, 6292.2134733158355, 6314.463270622003, 6343.651099189566, 6349.047642853563, 3772.6793222576116, 5441.945580544223, 5458.1169496523635, 5461.208124821918, 5467.507924286793, 23094.65358019631, 23152.884235578884, 24089.397765056015, 24110.638340424906, 24169.033238670432, 2059.773424923257, 2201.8128459081, 2314.5833749925005, 2583.4962182374443, 2728.1679087878188, 9736.41581505112, 10047.899521004769, 11898.10764192689, 12246.510139594406, 14707.55877323682, 1677.3039188736782, 1686.4603326935385, 1705.891170739017, 1734.1849330814498, 1783.0817129178483, 108452.0, 111055.99999999919, 112350.0, 113530.43234783856, 113852.4307378466, 10985.01134897863, 11007.944960275147, 11229.438528073564, 11245.887541124484, 11350.829737553944, 13541.39063742134, 13673.0, 13688.65778355549, 13702.93148534251, 13712.65718357029, 7864.606769661494, 8046.0344758629335, 8053.8791918120105, 8127.918720812834, 8132.512049277062, 7955.641996110189, 8000.199980002069, 8030.879536806952, 8080.969636386355, 8083.878741818878, 4438.378626992224, 4469.41897553316, 4471.754053527087, 4485.215087359699, 4521.525239849769, 1707.3426730708766, 1810.2396993262598, 1862.897540635251, 1894.242303078752, 1900.078461945955, 844.966201351939, 925.0, 927.9257659387233, 956.8756061711933, 991.4844280973854, 14405.92797036008, 19743.21027158926, 20046.89976550108, 20100.899495502723, 20462.0, 795.383577727266, 939.9953000234955, 958.2765012415582, 961.5769061612868, 1019.4800651667555, 10281.794364112677, 10581.576736930436, 10617.0, 10633.73415664599, 10651.0, 639174.0, 646840.0, 651350.0, 652457.0, 655361.723191381, 102527.0, 120339.19491207639, 122966.15550766746, 126255.3687231558, 127239.36380318039, 942.853857652065, 950.4772375193616, 961.6730311694058, 963.1861077389594, 997.4514017290461, 17183.31266749316, 17450.91274543619, 17609.735853961945, 17855.642887142443, 17990.730139048188, 51300.48699512957, 51803.48196517987, 52834.73582632062, 52923.47076529185, 54653.0, 76103.61948190223, 76564.85152722825, 77188.61405692935, 77227.61386193032, 77434.0, 6904.551204171713, 7689.3464055555905, 7868.095169055403, 7981.561014144278, 8127.471714338549, 9449.574769135543, 12312.569060082855, 12920.0309976754, 12929.094963352474, 13286.0, 11235.382053986783, 11508.654740357802, 11559.479823407799, 11574.074074074222, 11596.536138554364, 10699.0, 11012.669619911416, 11150.0, 11453.770924581295, 11513.769724605629, 11517.136214784092, 11547.422628868691, 11703.063754899418, 11874.881251187722, 11896.988755955494, 6877.58734475933, 7831.686732530635, 7882.832221139232, 8137.471064380797, 8201.507909525448, 11291.491882865126, 11305.773884522428, 11593.82609260845, 11628.6511404658, 11697.0], "unit": "score", "name": "jquery.html", "value": 9313.159970498036}, {"lowerIsBetter": false, "replicates": [1386.7850483175098, 1519.073365247199, 1525.5880912153716, 1756.5959829239275, 1933.8742981706189, 7822.374210063188, 8445.66217351306, 8593.527355995426, 8598.484090954547, 8600.8709869352, 8352.0, 8588.398812083156, 8590.699325523608, 8604.483730976146, 8617.87073193901, 2140.2295173737443, 2385.6183010718287, 2430.817688673352, 2455.1897873701637, 2457.213691618683, 2462.5813611685967, 2467.679201703776, 2471.0856982916257, 2472.7898128659085, 2474.604063349864, 2301.976980230193, 2306.780855818703, 2353.082297903819, 2428.441458464554, 2443.743406942272, 2402.9279121626205, 2444.8899799509063, 2445.6942882139733, 2452.534018536481, 2464.112919349033, 2427.1383658801046, 2433.829631925766, 2453.5092981403614, 2455.2511483997373, 2461.9876900615563, 8674.869876951787, 8725.520096394695, 8754.080821513713, 8769.561521923943, 8770.824583508358, 5176.197689358156, 5300.628955973083, 5321.494458026481, 5331.0, 5332.600054995852, 3020.41101985113, 3514.1039035046156, 3664.798436086014, 3665.157013886794, 3685.023468780775, 1029.7322696098993, 1056.6513050693252, 1060.5470505407563, 1072.131573425528, 1072.3137192197, 12001.519939202508, 12083.637490875288, 12087.818682719766, 12090.939545302217, 12113.576024839089, 4757.119932812441, 4808.63982481464, 4835.298881662139, 4835.8065677372515, 4880.755962201875, 10132.088112069949, 10142.442165680959, 10145.594176232868, 10156.593736250616, 10163.339382940085, 5304.071787437183, 5306.575473962073, 5313.0436521425745, 5316.308879845596, 5321.760520776575, 4309.956900430955, 4452.443444569429, 4658.1848176568965, 4764.857054288377, 4789.114013907439, 3868.6518213360932, 3928.057266256078, 3938.487996560486, 3963.742356746802, 3976.8409263629133, 1287.0475847872694, 1297.1827748518374, 1299.27240745183, 1308.6662900960384, 1310.6592286005707, 1120.1430905357408, 1140.4183866228118, 1141.50915106505, 1367.7811550151928, 1368.6988862450246, 8149.959250203711, 8214.958925205336, 8257.42198046131, 8261.752147435587, 8266.875996860052, 504.954743680585, 516.3622925686774, 517.5936889541767, 518.4219595151454, 523.0689372916215, 6171.537134714825, 6171.814845554641, 6226.688665566794, 6231.2522497300615, 6237.594556353823, 10289.22830787694, 10629.787404251872, 10775.730606734738, 10808.297460665031, 10817.675469735921, 9966.900330996597, 10547.84178237327, 10555.050045495942, 10647.574097036031, 10683.83974240387, 7994.0407151142235, 8147.959260203661, 8180.590970451453, 8218.589070546448, 8227.053888802744, 7917.7624671259955, 7929.088154862265, 7998.080220774453, 8032.0, 8054.919450805417, 9184.908150918405, 9279.62881484733, 9396.812063758824, 9449.905500944902, 9560.665376711782, 9712.805743884941, 9794.510274486105, 9811.607535698493, 9862.704118876445, 10068.39589624625, 6801.387875091265, 6984.3364880336285, 7217.927820721936, 7499.812504687427, 7626.923730762844, 8153.306968907709, 8180.509169449852, 8195.67217311301, 8233.917660823316, 8239.340852731648, 7158.355747982707, 7288.526245794006, 7314.414846812136, 7404.185539590749, 7454.142773581108, 6038.486728628116, 7125.5368401053765, 7147.213806481179, 7230.875272112369, 7281.963590182015, 6477.0, 7613.505122167041, 7668.309852113113, 7675.654595543326, 7676.961615191888, 6366.045093235866, 6659.800205993828, 6909.378155965987, 7018.368346848808, 7030.261822508563, 6955.304469553004, 7074.257202993612, 7080.185778635523, 7104.7513337035025, 7108.857822843411], "unit": "score", "name": "mootools.html", "value": 4780.420190488031}, {"lowerIsBetter": false, "replicates": [313.1904028087394, 317.25919976854044, 322.9192701824544, 327.62486952439457, 331.0482363205783, 6489.351064893508, 6571.5071369647285, 6576.0, 6597.967010164954, 6633.270340262573, 6423.325550817167, 6506.674666266679, 6530.95504719245, 6553.541252112354, 6585.506087043467, 4585.862424127265, 4710.458297295821, 4740.217864052427, 4849.854504364875, 4874.756262186886, 4795.784189711454, 4808.831690890819, 4810.35060266863, 4814.181589129839, 4822.156122678535, 4718.221493453576, 4740.620750339965, 4750.501197374278, 4774.665773395864, 4782.545658162469, 3888.494495715539, 4572.679912406133, 4662.160811054043, 4817.060673168716, 4854.781534830941, 4104.322786740185, 4106.650934670557, 4111.218868414991, 4113.835446582133, 4114.300568903263, 6303.7163327649905, 6313.621182729051, 6366.968165159191, 6519.608823470559, 6523.315051919528, 5628.42404126841, 5633.746481408347, 5634.577406694514, 5642.319898048435, 5664.773409063632, 5635.746391412396, 6839.0, 6945.305469453065, 6962.512624116314, 6972.895406568906, 4314.395984562164, 4504.617107545862, 4528.954710452919, 4542.613877820356, 4600.309953506967, 7382.0, 7394.408447324201, 7457.813554661123, 7469.365103966169, 7478.439117066242, 4601.125786100697, 4605.815767369268, 4607.516210797885, 4608.769561521911, 4609.492955774862, 4656.324832899279, 4660.13787449323, 4669.042846216517, 4669.2062349400685, 4671.5094915034115, 5546.611737178358, 5548.805791797348, 5549.361823390281, 5549.500544950974, 5549.861253468695, 5805.809808989147, 6824.897626535606, 7047.119110111236, 7069.399101076466, 7078.893816592756, 1591.1248813152952, 1601.0793793568744, 1615.1439736939442, 1618.0, 1619.3846338391572, 923.2475532441173, 968.5399435268332, 994.0010289658846, 1021.7496713305288, 1049.7060822969581, 6285.402886725754, 6407.615543067431, 6432.292447830735, 6491.415772580491, 6519.119918810995, 5101.846944591594, 6259.812205633838, 7106.5736055836815, 7117.537360071682, 7378.889316660255, 6683.6324002180345, 6693.0, 6701.229358623723, 6704.83237919056, 6710.530262881552, 3193.9041828745176, 3839.0210496323257, 3871.915863558208, 3890.280298144852, 3901.5318161820765, 7400.074857671859, 7604.80987975294, 7710.884336734954, 7743.690252389842, 7750.7674769756995, 6170.321264660884, 6464.806055818333, 7349.375303099297, 7571.734989275349, 7615.390768738488, 7674.884876726854, 7679.0, 7682.615869206629, 7690.230976902377, 7700.229977002367, 7319.853602927805, 7329.120505539369, 7336.303051210127, 7342.412606991535, 7369.0, 6919.37725604698, 7200.927990720026, 7267.0, 7281.817954551178, 7299.489035767445, 6304.6847657617855, 7676.0, 7708.961455192688, 7725.420593455401, 7740.1098873628, 4585.747783871919, 4615.169269531517, 4621.5904149233465, 4629.745364004877, 4643.419572553431, 7291.30732580404, 7305.561666300039, 7315.817104572428, 7317.938898859529, 7318.707251709872, 6777.6272305023695, 7049.612271325126, 7077.469189810888, 7079.787606371817, 7105.786826395217, 7001.544899581511, 7038.190608080136, 7040.225575186828, 7053.329933656294, 7062.611556364449, 6864.347886950732, 6889.689963951734, 6895.138107736533, 6905.274946130584, 6905.482088843256, 6789.1852977643, 7034.155901291673, 7040.718371265092, 7072.575645461289, 7075.575465472089, 6373.713182906873, 6663.967085101623, 6812.65936703153, 6820.658967051727, 6955.547889387173], "unit": "score", "name": "prototype.html", "value": 5088.0468111386535}, {"lowerIsBetter": false, "replicates": [623.8066199478164, 695.9965200173998, 701.6526819224484, 731.9048523691919, 747.4730315127836, 14736.410543578257, 14918.776218356734, 14938.925305373485, 14944.252787360616, 14968.77546836797, 13370.331483425814, 13584.728305433886, 13594.388252528635, 13665.043446958716, 13990.16059036456, 847.5338563789921, 848.9702860399888, 857.6140736668493, 857.7169534053778, 860.1656393298522, 730.6164263761541, 838.8993320801512, 874.3704532736451, 876.4828751036887, 877.4559772940786, 897.8563429851225, 917.3257655623112, 921.0237148622482, 923.224491427201, 924.2421214604027, 894.9642014319419, 914.7804526913559, 916.4363916191561, 917.6191880369638, 918.7978644698155, 659.8713250916073, 664.2162248546713, 674.6559254780049, 675.746595026865, 676.1649363036695, 12935.223886566837, 12951.740965180737, 12977.0, 12977.41601627929, 13013.0, 11047.171462140372, 11218.775624487545, 11225.326480411202, 11338.0, 11377.0, 8390.916090839075, 9140.994490606028, 9262.907370926272, 9427.387219830689, 9450.52747362629, 2999.925001874949, 3426.3318652862704, 3468.757186996911, 3528.6118526962014, 3535.222251104753, 80590.0, 81163.0, 81289.96775080611, 81482.3703525932, 81550.0, 8046.681516252291, 8286.917130828735, 8287.295579875778, 8348.958255208685, 8683.305335573265, 18801.623967520572, 18856.52858678544, 18857.528561786065, 18897.622047559245, 18926.43220703381, 7295.245744770543, 7421.3297172257135, 7816.804579885548, 7817.335526480313, 7832.412569057344, 3943.546492153439, 3989.3018721723583, 4002.3596224603925, 4024.6981476389387, 4087.8773636790943, 2963.955540666892, 3436.759426840147, 3518.4546395308766, 3518.5601799775027, 3521.454174602941, 611.5382885921101, 615.0743131586933, 617.4998251416367, 621.9129321894939, 628.1425853709744, 479.4917387569188, 570.1419363857368, 576.230731972821, 596.2249076200922, 607.9209702738705, 7960.800979975547, 7975.561344126013, 8095.55474448911, 8184.345252379795, 8319.42931931939, 565.3329071695439, 568.8492549474392, 580.1095318685881, 595.3718826637938, 598.9460948514654, 3689.797061161662, 3692.8522859085874, 3706.0, 3782.659560639556, 3932.2921874062945, 93940.53029734807, 94457.52771236237, 96076.51961740147, 96093.0390696084, 96249.0, 58870.41129588649, 80416.59791701005, 80417.59791201123, 80609.0, 83050.58474707708, 67374.0, 67841.99999999901, 68199.65900170467, 68678.62642747218, 68691.65654171698, 8067.314278286177, 8174.427790054639, 8240.258376746122, 8277.461964972257, 8350.665973361241, 44320.77839610782, 45257.547424525335, 50751.492485074676, 51209.48790512196, 52072.73963630158, 61135.0, 70391.64804175946, 71023.28976710167, 72356.0, 73066.0, 11181.888181118085, 11510.769784604428, 11529.711757206136, 11812.0, 12192.939035305122, 10388.844167337647, 11644.25336199543, 12008.159428840247, 12197.573084941985, 12314.0, 11208.607698730506, 11218.607348742755, 11332.716682082684, 11478.655640330804, 11683.0, 11035.0, 11044.447777611247, 11340.979311861973, 11452.0, 11734.480075030322, 10448.686539403527, 10526.631537900024, 10836.620718274822, 10893.836592451278, 10911.399873007062, 9013.233875120688, 9094.954525227597, 9102.499362535124, 9402.059794020817, 9441.716748497282, 8585.742427727178, 8801.0, 8863.49144062413, 9296.953515232652, 9307.72076837696], "unit": "score", "name": "yui.html", "value": 6669.695889683079}], "name": "dromaeo_css", "value": 7395.5059273022025}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 109.0, 159.0, 88.0, 152.0, 164.0, 166.0, 110.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 128.1}, {"lowerIsBetter": true, "replicates": [161.0, 121.0, 144.0, 159.0, 119.0, 141.0, 141.0, 120.0, 161.0, 122.0], "unit": "score", "name": "audio-beat-detection", "value": 138.9}, {"lowerIsBetter": true, "replicates": [185.0, 184.0, 185.0, 181.0, 186.0, 183.0, 185.0, 181.0, 182.0, 182.0], "unit": "score", "name": "audio-dft", "value": 183.4}, {"lowerIsBetter": true, "replicates": [79.0, 75.0, 87.0, 79.0, 56.0, 57.0, 56.0, 79.0, 83.0, 55.0], "unit": "score", "name": "audio-fft", "value": 70.6}, {"lowerIsBetter": true, "replicates": [101.0, 90.0, 132.0, 80.0, 77.0, 87.0, 110.0, 76.0, 76.0, 110.0], "unit": "score", "name": "audio-oscillator", "value": 93.9}, {"lowerIsBetter": true, "replicates": [89.0, 87.0, 135.0, 86.0, 92.0, 131.0, 120.0, 86.0, 89.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 100.0}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 93.6}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 86.0, 86.0, 85.0, 86.0, 87.0, 88.0, 84.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 85.6}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 56.0, 54.0, 53.0, 53.0, 55.0, 66.0, 56.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 55.6}, {"lowerIsBetter": true, "replicates": [66.0, 69.0, 69.0, 74.0, 72.0, 68.0, 70.0, 67.0, 66.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.8}, {"lowerIsBetter": true, "replicates": [100.0, 106.0, 108.0, 111.0, 105.0, 81.0, 82.0, 83.0, 85.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 94.3}, {"lowerIsBetter": true, "replicates": [130.0, 124.0, 128.0, 105.0, 116.0, 131.0, 131.0, 135.0, 116.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.7}, {"lowerIsBetter": true, "replicates": [139.0, 126.0, 138.0, 137.0, 139.0, 127.0, 139.0, 136.0, 169.0, 175.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 142.5}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 75.0, 97.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.9}], "name": "kraken", "value": 1448.9}]} 22:30:16 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1448.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7395.51"}}} 22:30:16 INFO - Return code: 0 22:30:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:30:16 INFO - # TBPL SUCCESS # 22:30:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:30:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:30:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:30:16 INFO - 2016-03-03 22:30:16.234 screenresolution[2254:19506] starting screenresolution argv=screenresolution get 22:30:16 INFO - 2016-03-03 22:30:16.236 screenresolution[2254:19506] Display 0: 1600x1200x32@60 22:30:16 INFO - 2016-03-03 22:30:16.242 screenresolution[2255:19509] starting screenresolution argv=screenresolution list 22:30:16 INFO - Available Modes on Display 0 22:30:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:30:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:30:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:30:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:30:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:30:16 INFO - Intel Iris: 22:30:16 INFO - Chipset Model: Intel Iris 22:30:16 INFO - Type: GPU 22:30:16 INFO - Bus: Built-In 22:30:16 INFO - VRAM (Dynamic, Max): 1536 MB 22:30:16 INFO - Vendor: Intel (0x8086) 22:30:16 INFO - Device ID: 0x0a2e 22:30:16 INFO - Revision ID: 0x0009 22:30:16 INFO - Displays: 22:30:16 INFO - Display: 22:30:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:30:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:30:16 INFO - Display Serial Number: 1600x1200 60 22:30:16 INFO - Main Display: Yes 22:30:16 INFO - Mirror: Off 22:30:16 INFO - Online: Yes 22:30:16 INFO - Rotation: Supported 22:30:16 INFO - Return code: 0 22:30:16 INFO - Running post-action listener: _resource_record_post_action 22:30:16 INFO - Running post-run listener: _resource_record_post_run 22:30:17 INFO - Total resource usage - Wall time: 1206s; CPU: 52.0%; Read bytes: 8192; Write bytes: 704155648; Read time: 0; Write time: 1925 22:30:17 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401725952; Write bytes: 404162048; Read time: 30056; Write time: 746 22:30:17 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 4096; Write bytes: 299145728; Read time: 0; Write time: 1176 22:30:17 INFO - Running post-run listener: _upload_blobber_files 22:30:17 INFO - Blob upload gear active. 22:30:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:30:17 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 22:30:17 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'] 22:30:17 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 22:30:17 INFO - (blobuploader) - INFO - Open directory for files ... 22:30:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:30:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:30:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:18 INFO - (blobuploader) - INFO - Done attempting. 22:30:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:30:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:30:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:30:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:30:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:30:19 INFO - (blobuploader) - INFO - Done attempting. 22:30:19 INFO - (blobuploader) - INFO - Iteration through files over. 22:30:19 INFO - Return code: 0 22:30:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:30:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:30:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57034e10795e4594a3a83c186244cb947ac50ea4d2b57893641317de1d19c072a091c08ce6cbb256590487d9c366282f3b35723087f6f367c004a738232aca83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ab716c65ec216fb415570692467af112822dfdb9e0324f55ae5d1aa1a3fb618e5e55351c03bf21dce86153fa50e09708890f9a3f1b4495f1fa435674063afab"} 22:30:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:30:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:30:19 INFO - Contents: 22:30:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57034e10795e4594a3a83c186244cb947ac50ea4d2b57893641317de1d19c072a091c08ce6cbb256590487d9c366282f3b35723087f6f367c004a738232aca83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ab716c65ec216fb415570692467af112822dfdb9e0324f55ae5d1aa1a3fb618e5e55351c03bf21dce86153fa50e09708890f9a3f1b4495f1fa435674063afab"} 22:30:19 INFO - Running post-run listener: copy_logs_to_upload_dir 22:30:19 INFO - Copying logs to upload dir... 22:30:19 INFO - mkdir: /builds/slave/test/build/upload/logs 22:30:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.903941 ========= master_lag: 25.96 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2016-03-03 22:30:45.939576) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-03-03 22:30:45.943781) ========= 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.i16reHACz3/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.8Qq3KXTfUD/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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/57034e10795e4594a3a83c186244cb947ac50ea4d2b57893641317de1d19c072a091c08ce6cbb256590487d9c366282f3b35723087f6f367c004a738232aca83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ab716c65ec216fb415570692467af112822dfdb9e0324f55ae5d1aa1a3fb618e5e55351c03bf21dce86153fa50e09708890f9a3f1b4495f1fa435674063afab"} build_url:https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011329 build_url: 'https://queue.taskcluster.net/v1/task/FWFGOMRhSJ6qvY_obRJlbQ/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57034e10795e4594a3a83c186244cb947ac50ea4d2b57893641317de1d19c072a091c08ce6cbb256590487d9c366282f3b35723087f6f367c004a738232aca83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ab716c65ec216fb415570692467af112822dfdb9e0324f55ae5d1aa1a3fb618e5e55351c03bf21dce86153fa50e09708890f9a3f1b4495f1fa435674063afab"}' ========= master_lag: 2.55 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-03-03 22:30:48.501818) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:30:48.502191) ========= 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.i16reHACz3/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.8Qq3KXTfUD/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005267 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:30:48.627476) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-03 22:30:48.627798) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-03 22:30:48.647892) ========= ========= Total master_lag: 48.11 =========