builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0023 starttime: 1457632004.99 results: success (0) buildid: 20160310072522 builduid: a1236d323ee54fd99ae4b1105cab339d revision: 36bd53d339e68ef345ae92dbacfa749c67dcc2d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:44.991328) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:44.991724) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:44.992033) ========= 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.UShJEVb8P8/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.5TkN4JTUJG/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.005653 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:45.017638) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:45.017933) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:45.040228) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-03-10 09:46:45.040618) ========= 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.UShJEVb8P8/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.5TkN4JTUJG/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005736 ========= master_lag: 6.39 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-03-10 09:46:51.433379) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:51.433712) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:51.434068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-10 09:46:51.434340) ========= 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.UShJEVb8P8/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.5TkN4JTUJG/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-03-10 09:46:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.4M=0s 2016-03-10 09:46:52 (29.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.559104 ========= master_lag: 1.40 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-10 09:46:53.393634) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:53.393954) ========= 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.UShJEVb8P8/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.5TkN4JTUJG/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025146 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:53.446260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-10 09:46:53.446620) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 36bd53d339e68ef345ae92dbacfa749c67dcc2d0 --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 36bd53d339e68ef345ae92dbacfa749c67dcc2d0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UShJEVb8P8/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.5TkN4JTUJG/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-03-10 09:46:53,525 truncating revision to first 12 chars 2016-03-10 09:46:53,525 Setting DEBUG logging. 2016-03-10 09:46:53,525 attempt 1/10 2016-03-10 09:46:53,525 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/36bd53d339e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-10 09:46:55,170 unpacking tar archive at: fx-team-36bd53d339e6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.892451 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-10 09:46:55.358515) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:55.358938) ========= echo 36bd53d339e68ef345ae92dbacfa749c67dcc2d0 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'36bd53d339e68ef345ae92dbacfa749c67dcc2d0'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UShJEVb8P8/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.5TkN4JTUJG/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 36bd53d339e68ef345ae92dbacfa749c67dcc2d0 program finished with exit code 0 elapsedTime=0.005604 script_repo_revision: '36bd53d339e68ef345ae92dbacfa749c67dcc2d0' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:55.411196) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:55.414521) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-10 09:46:55.429018) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-03-10 09:46:55.429416) ========= /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.UShJEVb8P8/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.5TkN4JTUJG/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:46:55 INFO - MultiFileLogger online at 20160310 09:46:55 in /builds/slave/test 09:46:55 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 09:46:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:46:55 INFO - {'append_to_log': False, 09:46:55 INFO - 'base_work_dir': '/builds/slave/test', 09:46:55 INFO - 'blob_upload_branch': 'Fx-Team', 09:46:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:46:55 INFO - 'branch': 'Fx-Team', 09:46:55 INFO - 'buildbot_json_path': 'buildprops.json', 09:46:55 INFO - 'config_files': ('talos/mac_config.py',), 09:46:55 INFO - 'default_actions': ('clobber', 09:46:55 INFO - 'read-buildbot-config', 09:46:55 INFO - 'download-and-extract', 09:46:55 INFO - 'populate-webroot', 09:46:55 INFO - 'create-virtualenv', 09:46:55 INFO - 'install', 09:46:55 INFO - 'run-tests'), 09:46:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:46:55 INFO - 'download_minidump_stackwalk': True, 09:46:55 INFO - 'download_symbols': 'ondemand', 09:46:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:46:55 INFO - 'tooltool.py': '/tools/tooltool.py', 09:46:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:46:55 INFO - '/tools/misc-python/virtualenv.py')}, 09:46:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:46:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:46:55 INFO - 'installer_path': 'installer.exe', 09:46:55 INFO - 'log_level': 'info', 09:46:55 INFO - 'log_name': 'talos', 09:46:55 INFO - 'log_to_console': True, 09:46:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:46:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:46:55 INFO - 'opt_config_files': (), 09:46:55 INFO - 'pip_index': False, 09:46:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:55 INFO - 'cmd': ('bash', 09:46:55 INFO - '-c', 09:46:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:55 INFO - 'enabled': True, 09:46:55 INFO - 'halt_on_failure': False, 09:46:55 INFO - 'name': 'check_screen_resolution'},), 09:46:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:55 INFO - 'cmd': ('bash', 09:46:55 INFO - '-c', 09:46:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:55 INFO - 'enabled': True, 09:46:55 INFO - 'halt_on_failure': False, 09:46:55 INFO - 'name': 'check_screen_resolution'},), 09:46:55 INFO - 'run_cmd_checks_enabled': True, 09:46:55 INFO - 'sps_profile': False, 09:46:55 INFO - 'sps_profile_interval': 0, 09:46:55 INFO - 'suite': 'dromaeojs', 09:46:55 INFO - 'system_bits': '32', 09:46:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:46:55 INFO - 'title': 't-yosemite-r7-0023', 09:46:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:46:55 INFO - 'use_talos_json': True, 09:46:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:46:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:46:55 INFO - 'work_dir': 'build'} 09:46:55 INFO - ##### 09:46:55 INFO - ##### Running clobber step. 09:46:55 INFO - ##### 09:46:55 INFO - Running pre-action listener: _resource_record_pre_action 09:46:55 INFO - Running main action method: clobber 09:46:55 INFO - rmtree: /builds/slave/test/build 09:46:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:46:57 INFO - Running post-action listener: _resource_record_post_action 09:46:57 INFO - ##### 09:46:57 INFO - ##### Running read-buildbot-config step. 09:46:57 INFO - ##### 09:46:57 INFO - Running pre-action listener: _resource_record_pre_action 09:46:57 INFO - Running main action method: read_buildbot_config 09:46:57 INFO - Using buildbot properties: 09:46:57 INFO - { 09:46:57 INFO - "project": "", 09:46:57 INFO - "product": "firefox", 09:46:57 INFO - "script_repo_revision": "production", 09:46:57 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:46:57 INFO - "repository": "", 09:46:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:46:57 INFO - "buildid": "20160310072522", 09:46:57 INFO - "pgo_build": "False", 09:46:57 INFO - "basedir": "/builds/slave/test", 09:46:57 INFO - "buildnumber": 517, 09:46:57 INFO - "slavename": "t-yosemite-r7-0023", 09:46:57 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:46:57 INFO - "platform": "yosemite_r7", 09:46:57 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:46:57 INFO - "branch": "fx-team", 09:46:57 INFO - "repo_path": "integration/fx-team", 09:46:57 INFO - "revision": "36bd53d339e68ef345ae92dbacfa749c67dcc2d0", 09:46:57 INFO - "stage_platform": "macosx64", 09:46:57 INFO - "builduid": "a1236d323ee54fd99ae4b1105cab339d", 09:46:57 INFO - "slavebuilddir": "test" 09:46:57 INFO - } 09:46:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 09:46:57 INFO - Running post-action listener: _resource_record_post_action 09:46:57 INFO - ##### 09:46:57 INFO - ##### Running download-and-extract step. 09:46:57 INFO - ##### 09:46:57 INFO - Running pre-action listener: _resource_record_pre_action 09:46:57 INFO - Running main action method: download_and_extract 09:46:57 INFO - mkdir: /builds/slave/test/build/tests 09:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:46:57 INFO - https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:46:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:46:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:46:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:46:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 09:46:58 INFO - Downloaded 1199 bytes. 09:46:58 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:46:58 INFO - Using the following test package requirements: 09:46:58 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:46:58 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:46:58 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:46:58 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:46:58 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:46:58 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:46:58 INFO - u'jsshell-mac.zip'], 09:46:58 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:46:58 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:46:58 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:46:58 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:46:58 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:46:58 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:46:58 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:46:58 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:46:58 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:46:58 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:46:58 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:46:58 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:46:58 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')]} 09:46:58 INFO - https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:46:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:46:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:46:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:46:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:46:59 INFO - Downloaded 17867502 bytes. 09:46:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:46:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:47:02 INFO - Return code: 0 09:47:02 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:47: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')]} 09:47:02 INFO - https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:47:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:47:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:47:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:47:03 INFO - Downloaded 17867502 bytes. 09:47:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:47:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:47:07 INFO - Return code: 0 09:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:07 INFO - https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:47:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:47:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:47:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:47:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:47:08 INFO - Downloaded 10845161 bytes. 09:47:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:47:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:47:08 INFO - Return code: 0 09:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:08 INFO - https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:47:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:47:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:47:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 09:47:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 09:47:11 INFO - Downloaded 110985736 bytes. 09:47:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:47:11 INFO - mkdir: /builds/slave/test/properties 09:47:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:47:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:47:11 INFO - Contents: 09:47:11 INFO - build_url:https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:47:11 INFO - Running post-action listener: _resource_record_post_action 09:47:11 INFO - Running post-action listener: set_extra_try_arguments 09:47:11 INFO - ##### 09:47:11 INFO - ##### Running populate-webroot step. 09:47:11 INFO - ##### 09:47:11 INFO - Running pre-action listener: _resource_record_pre_action 09:47:11 INFO - Running main action method: populate_webroot 09:47:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:47:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:47:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:47:11 INFO - '--tppagecycles', 09:47:11 INFO - '7'], 09:47:11 INFO - 'tests': ['tsvgm']}}, 09:47:11 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:47:11 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:47:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:47:11 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 09:47:11 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:47:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:47:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:11 INFO - 'talos_options': ['--e10s'], 09:47:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:11 INFO - 'tests': ['damp', 'tps']}, 09:47:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:11 INFO - 'talos_options': ['--e10s'], 09:47:11 INFO - 'tests': ['damp', 'tps']}, 09:47:11 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:47:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:47:11 INFO - 'other': {'tests': ['a11yr', 09:47:11 INFO - 'ts_paint', 09:47:11 INFO - 'tpaint', 09:47:11 INFO - 'sessionrestore', 09:47:11 INFO - 'sessionrestore_no_auto_restore', 09:47:11 INFO - 'tabpaint']}, 09:47:11 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:47:11 INFO - 'tests': ['a11yr', 09:47:11 INFO - 'ts_paint', 09:47:11 INFO - 'tpaint', 09:47:11 INFO - 'sessionrestore', 09:47:11 INFO - 'sessionrestore_no_auto_restore', 09:47:11 INFO - 'tabpaint']}, 09:47:11 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:47:11 INFO - 'tests': ['a11yr', 09:47:11 INFO - 'ts_paint', 09:47:11 INFO - 'tpaint', 09:47:11 INFO - 'sessionrestore', 09:47:11 INFO - 'sessionrestore_no_auto_restore', 09:47:11 INFO - 'tabpaint']}, 09:47:11 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:47:11 INFO - 'tests': ['a11yr', 09:47:11 INFO - 'ts_paint', 09:47:11 INFO - 'tpaint', 09:47:11 INFO - 'sessionrestore', 09:47:11 INFO - 'sessionrestore_no_auto_restore', 09:47:11 INFO - 'tabpaint']}, 09:47:11 INFO - 'other_l64': {'tests': ['a11yr', 09:47:11 INFO - 'ts_paint', 09:47:11 INFO - 'tpaint', 09:47:11 INFO - 'sessionrestore', 09:47:11 INFO - 'sessionrestore_no_auto_restore', 09:47:11 INFO - 'tabpaint']}, 09:47:11 INFO - 'other_nol64': {'tests': ['a11yr', 09:47:11 INFO - 'ts_paint', 09:47:11 INFO - 'tpaint', 09:47:11 INFO - 'sessionrestore', 09:47:11 INFO - 'sessionrestore_no_auto_restore', 09:47:11 INFO - 'tabpaint']}, 09:47:11 INFO - 'svgr': {'tests': ['tsvgx', 09:47:11 INFO - 'tsvgr_opacity', 09:47:11 INFO - 'tart', 09:47:11 INFO - 'tscrollx', 09:47:11 INFO - 'cart']}, 09:47:11 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:47:11 INFO - 'tests': ['tsvgx', 09:47:11 INFO - 'tsvgr_opacity', 09:47:11 INFO - 'tart', 09:47:11 INFO - 'tscrollx', 09:47:11 INFO - 'cart']}, 09:47:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:11 INFO - 'tests': ['tp5o']}, 09:47:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:11 INFO - 'talos_options': ['--e10s'], 09:47:11 INFO - 'tests': ['tp5o']}, 09:47:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:11 INFO - 'talos_options': ['--xperf_path', 09:47:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:11 INFO - 'tests': ['tp5n']}, 09:47:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:11 INFO - 'talos_options': ['--e10s', 09:47:11 INFO - '--xperf_path', 09:47:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:11 INFO - 'tests': ['tp5n']}}, 09:47:11 INFO - 'talos.zip': {'path': '', 09:47:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:47:11 INFO - Running post-action listener: _resource_record_post_action 09:47:11 INFO - ##### 09:47:11 INFO - ##### Running create-virtualenv step. 09:47:11 INFO - ##### 09:47:11 INFO - Running pre-action listener: _resource_record_pre_action 09:47:11 INFO - Running main action method: create_virtualenv 09:47:11 INFO - Creating virtualenv /builds/slave/test/build/venv 09:47:11 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 09:47:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:47:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:47:12 INFO - Using real prefix '/tools/python27' 09:47:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:47:12 INFO - Installing distribute.............................................................................................................................................................................................done. 09:47:15 INFO - Installing pip.................done. 09:47:15 INFO - Return code: 0 09:47:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:47: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')]} 09:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47: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')]} 09:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:15 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 0x103126a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10312e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc25a25d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, '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.UShJEVb8P8/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.5TkN4JTUJG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:15 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 09:47:15 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 09:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:15 INFO - 'HOME': '/Users/cltbld', 09:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:15 INFO - 'LOGNAME': 'cltbld', 09:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:15 INFO - 'MOZ_NO_REMOTE': '1', 09:47:15 INFO - 'NO_EM_RESTART': '1', 09:47:15 INFO - 'PAGER': '/bin/cat', 09:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:15 INFO - 'PWD': '/builds/slave/test', 09:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:15 INFO - 'SHELL': '/bin/bash', 09:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:47:15 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:15 INFO - 'USER': 'cltbld', 09:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:15 INFO - 'XPC_FLAGS': '0x0', 09:47:15 INFO - 'XPC_SERVICE_NAME': '0', 09:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:15 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 09:47:15 INFO - Cleaning up... 09:47:15 INFO - Return code: 0 09:47:15 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:47: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')]} 09:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47: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')]} 09:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:15 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 0x103126a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10312e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc25a25d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, '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.UShJEVb8P8/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.5TkN4JTUJG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:15 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 09:47:15 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 09:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:15 INFO - 'HOME': '/Users/cltbld', 09:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:15 INFO - 'LOGNAME': 'cltbld', 09:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:15 INFO - 'MOZ_NO_REMOTE': '1', 09:47:15 INFO - 'NO_EM_RESTART': '1', 09:47:15 INFO - 'PAGER': '/bin/cat', 09:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:15 INFO - 'PWD': '/builds/slave/test', 09:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:15 INFO - 'SHELL': '/bin/bash', 09:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:47:15 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:15 INFO - 'USER': 'cltbld', 09:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:15 INFO - 'XPC_FLAGS': '0x0', 09:47:15 INFO - 'XPC_SERVICE_NAME': '0', 09:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:16 INFO - Downloading/unpacking psutil>=0.7.1 09:47:16 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 09:47:16 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 09:47:16 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 09:47:16 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 09:47:16 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 09:47:16 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 09:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:47:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:19 INFO - Installing collected packages: psutil 09:47:19 INFO - Running setup.py install for psutil 09:47:19 INFO - building 'psutil._psutil_osx' extension 09:47:19 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 09:47:19 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 09:47:19 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 09:47:19 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 09:47:19 INFO - building 'psutil._psutil_posix' extension 09:47:19 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 09:47:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:47:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:47:20 INFO - ^ 09:47:20 INFO - 1 warning generated. 09:47:20 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 09:47:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:20 INFO - Successfully installed psutil 09:47:20 INFO - Cleaning up... 09:47:20 INFO - Return code: 0 09:47:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103126a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10312e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc25a25d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, '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.UShJEVb8P8/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.5TkN4JTUJG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:47:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:20 INFO - 'HOME': '/Users/cltbld', 09:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:20 INFO - 'LOGNAME': 'cltbld', 09:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:20 INFO - 'MOZ_NO_REMOTE': '1', 09:47:20 INFO - 'NO_EM_RESTART': '1', 09:47:20 INFO - 'PAGER': '/bin/cat', 09:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:20 INFO - 'PWD': '/builds/slave/test', 09:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:20 INFO - 'SHELL': '/bin/bash', 09:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:47:20 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:20 INFO - 'USER': 'cltbld', 09:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:20 INFO - 'XPC_FLAGS': '0x0', 09:47:20 INFO - 'XPC_SERVICE_NAME': '0', 09:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:47:23 INFO - Installing collected packages: mozsystemmonitor 09:47:23 INFO - Running setup.py install for mozsystemmonitor 09:47:24 INFO - Successfully installed mozsystemmonitor 09:47:24 INFO - Cleaning up... 09:47:24 INFO - Return code: 0 09:47:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:47:24 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')]} 09:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:24 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')]} 09:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:24 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 0x103126a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10312e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc25a25d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, '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.UShJEVb8P8/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.5TkN4JTUJG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:24 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 09:47:24 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 09:47:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:47:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:24 INFO - 'HOME': '/Users/cltbld', 09:47:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:24 INFO - 'LOGNAME': 'cltbld', 09:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:24 INFO - 'MOZ_NO_REMOTE': '1', 09:47:24 INFO - 'NO_EM_RESTART': '1', 09:47:24 INFO - 'PAGER': '/bin/cat', 09:47:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:24 INFO - 'PWD': '/builds/slave/test', 09:47:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:24 INFO - 'SHELL': '/bin/bash', 09:47:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:47:24 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:47:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:24 INFO - 'USER': 'cltbld', 09:47:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:24 INFO - 'XPC_FLAGS': '0x0', 09:47:24 INFO - 'XPC_SERVICE_NAME': '0', 09:47:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:24 INFO - Downloading/unpacking blobuploader==1.2.4 09:47:24 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 09:47:24 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 09:47:24 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 09:47:24 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 09:47:24 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 09:47:24 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 09:47:26 INFO - Downloading blobuploader-1.2.4.tar.gz 09:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:47:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:47:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:47:27 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 09:47:27 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 09:47:27 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 09:47:27 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 09:47:27 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 09:47:27 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 09:47:27 INFO - Downloading docopt-0.6.1.tar.gz 09:47:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:47:27 INFO - Installing collected packages: blobuploader, requests, docopt 09:47:27 INFO - Running setup.py install for blobuploader 09:47:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:47:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:47:28 INFO - Running setup.py install for requests 09:47:28 INFO - Running setup.py install for docopt 09:47:28 INFO - Successfully installed blobuploader requests docopt 09:47:28 INFO - Cleaning up... 09:47:28 INFO - Return code: 0 09:47:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47: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')]} 09:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47: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')]} 09:47:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:28 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 0x103126a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10312e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc25a25d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, '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.UShJEVb8P8/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.5TkN4JTUJG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:28 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 09:47:28 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 09:47:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:47:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:28 INFO - 'HOME': '/Users/cltbld', 09:47:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:28 INFO - 'LOGNAME': 'cltbld', 09:47:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:28 INFO - 'MOZ_NO_REMOTE': '1', 09:47:28 INFO - 'NO_EM_RESTART': '1', 09:47:28 INFO - 'PAGER': '/bin/cat', 09:47:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:28 INFO - 'PWD': '/builds/slave/test', 09:47:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:28 INFO - 'SHELL': '/bin/bash', 09:47:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:47:28 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:47:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:28 INFO - 'USER': 'cltbld', 09:47:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:28 INFO - 'XPC_FLAGS': '0x0', 09:47:28 INFO - 'XPC_SERVICE_NAME': '0', 09:47:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-m9tyF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-4bg0LD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-I0aumu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-XS855l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-2KC79R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-NKJmSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-QeL2ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-jCsVjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-BniUxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:29 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-9JUC8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5dqOmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-9IMUgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-BtSKn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-W90v0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-O4ipH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-oHUdwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:30 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-jy3H8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:47:30 INFO - Running setup.py install for manifestparser 09:47:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:47:30 INFO - Running setup.py install for mozcrash 09:47:31 INFO - Running setup.py install for mozdebug 09:47:31 INFO - Running setup.py install for mozdevice 09:47:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:47:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:47:31 INFO - Running setup.py install for mozfile 09:47:31 INFO - Running setup.py install for mozhttpd 09:47:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:47:31 INFO - Running setup.py install for mozinfo 09:47:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:47:31 INFO - Running setup.py install for mozInstall 09:47:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:47:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:47:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:47:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:47:31 INFO - Running setup.py install for mozleak 09:47:32 INFO - Running setup.py install for mozlog 09:47:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:47:32 INFO - Running setup.py install for moznetwork 09:47:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:47:32 INFO - Running setup.py install for mozprocess 09:47:32 INFO - Running setup.py install for mozprofile 09:47:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:47:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:47:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:47:32 INFO - Running setup.py install for mozrunner 09:47:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:47:32 INFO - Running setup.py install for mozscreenshot 09:47:33 INFO - Running setup.py install for moztest 09:47:33 INFO - Running setup.py install for mozversion 09:47:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:47:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:47:33 INFO - Cleaning up... 09:47:33 INFO - Return code: 0 09:47:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:33 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')]} 09:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:33 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')]} 09:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:33 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 0x103126a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10312e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc25a25d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, '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.UShJEVb8P8/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.5TkN4JTUJG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:33 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 09:47:33 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 09:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:33 INFO - 'HOME': '/Users/cltbld', 09:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:33 INFO - 'LOGNAME': 'cltbld', 09:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:33 INFO - 'MOZ_NO_REMOTE': '1', 09:47:33 INFO - 'NO_EM_RESTART': '1', 09:47:33 INFO - 'PAGER': '/bin/cat', 09:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:33 INFO - 'PWD': '/builds/slave/test', 09:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:33 INFO - 'SHELL': '/bin/bash', 09:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:47:33 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:33 INFO - 'USER': 'cltbld', 09:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:33 INFO - 'XPC_FLAGS': '0x0', 09:47:33 INFO - 'XPC_SERVICE_NAME': '0', 09:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:33 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-v1uOvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:33 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)) 09:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:33 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-hXJhZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:33 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-jN7S_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:33 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)) 09:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:33 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5yzNkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-L8zw28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-MEYiZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-V8uI3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-yOxXkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-I1MYfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Um37JP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-RoqUoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-GNbUVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-S1bq4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:34 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)) 09:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:34 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-85PJ5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:35 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)) 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:35 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-z_FXVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:35 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)) 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:35 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-uzjd8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:35 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)) 09:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:35 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-foIKRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:35 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)) 09:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:47:35 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)) 09:47:35 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)) 09:47:35 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)) 09:47:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:35 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 09:47:35 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 09:47:35 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 09:47:35 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 09:47:35 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 09:47:35 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 09:47:37 INFO - Downloading blessings-1.6.tar.gz 09:47:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:47:37 INFO - Installing collected packages: blessings 09:47:37 INFO - Running setup.py install for blessings 09:47:37 INFO - Successfully installed blessings 09:47:37 INFO - Cleaning up... 09:47:38 INFO - Return code: 0 09:47:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:47:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:38 INFO - Reading from file tmpfile_stdout 09:47:38 INFO - Current package versions: 09:47:38 INFO - blessings == 1.6 09:47:38 INFO - blobuploader == 1.2.4 09:47:38 INFO - docopt == 0.6.1 09:47:38 INFO - manifestparser == 1.1 09:47:38 INFO - mozInstall == 1.12 09:47:38 INFO - mozcrash == 0.17 09:47:38 INFO - mozdebug == 0.1 09:47:38 INFO - mozdevice == 0.48 09:47:38 INFO - mozfile == 1.2 09:47:38 INFO - mozhttpd == 0.7 09:47:38 INFO - mozinfo == 0.9 09:47:38 INFO - mozleak == 0.1 09:47:38 INFO - mozlog == 3.1 09:47:38 INFO - moznetwork == 0.27 09:47:38 INFO - mozprocess == 0.22 09:47:38 INFO - mozprofile == 0.28 09:47:38 INFO - mozrunner == 6.11 09:47:38 INFO - mozscreenshot == 0.1 09:47:38 INFO - mozsystemmonitor == 0.0 09:47:38 INFO - moztest == 0.7 09:47:38 INFO - mozversion == 1.4 09:47:38 INFO - psutil == 3.1.1 09:47:38 INFO - requests == 1.2.3 09:47:38 INFO - wsgiref == 0.1.2 09:47:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:38 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 0x103126a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10312e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc25a25d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, '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.UShJEVb8P8/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.5TkN4JTUJG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:38 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 09:47:38 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 09:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:38 INFO - 'HOME': '/Users/cltbld', 09:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:38 INFO - 'LOGNAME': 'cltbld', 09:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:38 INFO - 'MOZ_NO_REMOTE': '1', 09:47:38 INFO - 'NO_EM_RESTART': '1', 09:47:38 INFO - 'PAGER': '/bin/cat', 09:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:38 INFO - 'PWD': '/builds/slave/test', 09:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:38 INFO - 'SHELL': '/bin/bash', 09:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:47:38 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:38 INFO - 'USER': 'cltbld', 09:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:38 INFO - 'XPC_FLAGS': '0x0', 09:47:38 INFO - 'XPC_SERVICE_NAME': '0', 09:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:38 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)) 09:47:38 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)) 09:47:38 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)) 09:47:38 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)) 09:47:38 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)) 09:47:38 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)) 09:47:38 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)) 09:47:38 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)) 09:47:38 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)) 09:47:38 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)) 09:47:38 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)) 09:47:38 INFO - Cleaning up... 09:47:38 INFO - Return code: 0 09:47:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103126a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10312e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc25a25d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, '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.UShJEVb8P8/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.5TkN4JTUJG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:38 INFO - 'HOME': '/Users/cltbld', 09:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:38 INFO - 'LOGNAME': 'cltbld', 09:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:38 INFO - 'MOZ_NO_REMOTE': '1', 09:47:38 INFO - 'NO_EM_RESTART': '1', 09:47:38 INFO - 'PAGER': '/bin/cat', 09:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:38 INFO - 'PWD': '/builds/slave/test', 09:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:38 INFO - 'SHELL': '/bin/bash', 09:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:47:38 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:38 INFO - 'USER': 'cltbld', 09:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:38 INFO - 'XPC_FLAGS': '0x0', 09:47:38 INFO - 'XPC_SERVICE_NAME': '0', 09:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:38 INFO - Downloading/unpacking jsonschema 09:47:38 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 09:47:38 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 09:47:38 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 09:47:38 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 09:47:38 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 09:47:38 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 09:47:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:47:41 INFO - Downloading/unpacking functools32 (from jsonschema) 09:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:41 INFO - Downloading functools32-3.2.3-2.tar.gz 09:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:47:41 INFO - warning: no files found matching '*.txt' 09:47:41 INFO - no previously-included directories found matching 'build' 09:47:41 INFO - no previously-included directories found matching 'dist' 09:47:41 INFO - no previously-included directories found matching '.git*' 09:47:41 INFO - Installing collected packages: jsonschema, functools32 09:47:41 INFO - Running setup.py install for functools32 09:47:41 INFO - warning: no files found matching '*.txt' 09:47:41 INFO - no previously-included directories found matching 'build' 09:47:41 INFO - no previously-included directories found matching 'dist' 09:47:41 INFO - no previously-included directories found matching '.git*' 09:47:41 INFO - Successfully installed jsonschema functools32 09:47:41 INFO - Cleaning up... 09:47:41 INFO - Return code: 0 09:47:41 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103126a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10312e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdc25a25d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, '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.UShJEVb8P8/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.5TkN4JTUJG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:41 INFO - 'HOME': '/Users/cltbld', 09:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:41 INFO - 'LOGNAME': 'cltbld', 09:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:41 INFO - 'MOZ_NO_REMOTE': '1', 09:47:41 INFO - 'NO_EM_RESTART': '1', 09:47:41 INFO - 'PAGER': '/bin/cat', 09:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:41 INFO - 'PWD': '/builds/slave/test', 09:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:41 INFO - 'SHELL': '/bin/bash', 09:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:47:41 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:41 INFO - 'USER': 'cltbld', 09:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:41 INFO - 'XPC_FLAGS': '0x0', 09:47:41 INFO - 'XPC_SERVICE_NAME': '0', 09:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:42 INFO - Downloading/unpacking flake8 09:47:42 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 09:47:42 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 09:47:42 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 09:47:42 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 09:47:42 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 09:47:42 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 09:47:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:47:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:47:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:47:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:47:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:47:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:47:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:47:45 INFO - no previously-included directories found matching 'docs/_build' 09:47:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:47:45 INFO - Running setup.py install for pep8 09:47:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:47:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:47:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:47:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:47:45 INFO - no previously-included directories found matching 'docs/_build' 09:47:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:47:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:47:45 INFO - Cleaning up... 09:47:46 INFO - Return code: 0 09:47:46 INFO - Running post-action listener: _resource_record_post_action 09:47:46 INFO - Running post-action listener: _start_resource_monitoring 09:47:46 INFO - Starting resource monitoring. 09:47:46 INFO - ##### 09:47:46 INFO - ##### Running install step. 09:47:46 INFO - ##### 09:47:46 INFO - Running pre-action listener: _resource_record_pre_action 09:47:46 INFO - Running main action method: install 09:47:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:46 INFO - Reading from file tmpfile_stdout 09:47:46 INFO - Detecting whether we're running mozinstall >=1.0... 09:47:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:47:46 INFO - Reading from file tmpfile_stdout 09:47:46 INFO - Output received: 09:47:46 INFO - Usage: mozinstall [options] installer 09:47:46 INFO - Options: 09:47:46 INFO - -h, --help show this help message and exit 09:47:46 INFO - -d DEST, --destination=DEST 09:47:46 INFO - Directory to install application into. [default: 09:47:46 INFO - "/builds/slave/test"] 09:47:46 INFO - --app=APP Application being installed. [default: firefox] 09:47:46 INFO - mkdir: /builds/slave/test/build/application 09:47:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:48:20 INFO - Reading from file tmpfile_stdout 09:48:20 INFO - Output received: 09:48:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:48:20 INFO - Running post-action listener: _resource_record_post_action 09:48:20 INFO - ##### 09:48:20 INFO - ##### Running run-tests step. 09:48:20 INFO - ##### 09:48:20 INFO - Running pre-action listener: _resource_record_pre_action 09:48:20 INFO - Running main action method: run_tests 09:48:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:48:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:48:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:48:20 INFO - 2016-03-10 09:48:20.823 screenresolution[1644:7505] starting screenresolution argv=screenresolution get 09:48:20 INFO - 2016-03-10 09:48:20.826 screenresolution[1644:7505] Display 0: 1600x1200x32@60 09:48:20 INFO - 2016-03-10 09:48:20.831 screenresolution[1645:7507] starting screenresolution argv=screenresolution list 09:48:20 INFO - Available Modes on Display 0 09:48:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:48:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:48:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:48:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:48:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:48:21 INFO - Intel Iris: 09:48:21 INFO - Chipset Model: Intel Iris 09:48:21 INFO - Type: GPU 09:48:21 INFO - Bus: Built-In 09:48:21 INFO - VRAM (Dynamic, Max): 1536 MB 09:48:21 INFO - Vendor: Intel (0x8086) 09:48:21 INFO - Device ID: 0x0a2e 09:48:21 INFO - Revision ID: 0x0009 09:48:21 INFO - Displays: 09:48:21 INFO - Display: 09:48:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:48:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:48:21 INFO - Display Serial Number: 1600x1200 60 09:48:21 INFO - Main Display: Yes 09:48:21 INFO - Mirror: Off 09:48:21 INFO - Online: Yes 09:48:21 INFO - Rotation: Supported 09:48:21 INFO - Return code: 0 09:48:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:48:21 INFO - Python 2.7.3 09:48:21 INFO - Return code: 0 09:48:21 INFO - grabbing minidump binary from tooltool 09:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:21 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 0x102fcad30>, '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 0x102deedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fc1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:48:21 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 09:48:21 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 09:48:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:48:21 INFO - Return code: 0 09:48:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:48:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:48:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:48:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:48:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:48:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:48:22 INFO - Return code: 0 09:48:22 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/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:48:22 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/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:48:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UShJEVb8P8/Render', 09:48:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:22 INFO - 'HOME': '/Users/cltbld', 09:48:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:22 INFO - 'LOGNAME': 'cltbld', 09:48:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:48:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:22 INFO - 'MOZ_NO_REMOTE': '1', 09:48:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:48:22 INFO - 'NO_EM_RESTART': '1', 09:48:22 INFO - 'PAGER': '/bin/cat', 09:48:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:22 INFO - 'PWD': '/builds/slave/test', 09:48:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:48:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:22 INFO - 'SHELL': '/bin/bash', 09:48:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5TkN4JTUJG/Listeners', 09:48:22 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 09:48:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:22 INFO - 'USER': 'cltbld', 09:48:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:22 INFO - 'XPC_FLAGS': '0x0', 09:48:22 INFO - 'XPC_SERVICE_NAME': '0', 09:48:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:22 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/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:48:22 INFO - mozversion application_buildid: 20160310072522 09:48:22 INFO - mozversion application_changeset: 36bd53d339e68ef345ae92dbacfa749c67dcc2d0 09:48:22 INFO - mozversion application_display_name: Nightly 09:48:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:48:22 INFO - mozversion application_name: Firefox 09:48:22 INFO - mozversion application_remotingname: firefox 09:48:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:48:22 INFO - mozversion application_vendor: Mozilla 09:48:22 INFO - mozversion application_version: 48.0a1 09:48:22 INFO - mozversion platform_buildid: 20160310072522 09:48:22 INFO - mozversion platform_changeset: 36bd53d339e68ef345ae92dbacfa749c67dcc2d0 09:48:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:48:22 INFO - mozversion platform_version: 48.0a1 09:48:22 INFO - using testdate: 1457632102 09:48:22 INFO - actual date: 1457632102 09:48:22 INFO - starting webserver on 'localhost:49270' 09:48:22 INFO - SUITE-START | Running 2 tests 09:48:22 INFO - TEST-START | dromaeo_css 09:48:22 INFO - operating with platform_type : mac_ 09:48:22 INFO - Initialising browser for dromaeo_css test... 09:48:22 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpJwOuUD/profile http://localhost:49270/getInfo.html) 09:48:24 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 09:48:24 INFO - PROCESS | 1663 | colorDepth:24 09:48:24 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 09:48:24 INFO - PROCESS | 1663 | __metrics 09:48:24 INFO - PROCESS | 1663 | 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" 09:48:24 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:48:25 INFO - TEST-INFO | 1663: exit 0 09:48:25 INFO - Browser initialized. 09:48:25 INFO - Running cycle 1/1 for dromaeo_css test... 09:48:25 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpJwOuUD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:48:37 INFO - PROCESS | 1666 | RSS: Main: 243351552 09:48:37 INFO - PROCESS | 1666 | 09:49:28 INFO - PROCESS | 1666 | 1457632168408 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] 09:51:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 09:51:40 INFO - PROCESS | 1666 | RSS: Main: 457744384 09:51:40 INFO - PROCESS | 1666 | 09:54:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 09:54:43 INFO - PROCESS | 1666 | RSS: Main: 374566912 09:54:43 INFO - PROCESS | 1666 | 09:57:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 09:57:46 INFO - PROCESS | 1666 | RSS: Main: 375160832 09:57:46 INFO - PROCESS | 1666 | 10:00:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 10:00:49 INFO - PROCESS | 1666 | RSS: Main: 334729216 10:00:49 INFO - PROCESS | 1666 | 10:03:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 10:03:52 INFO - PROCESS | 1666 | RSS: Main: 337072128 10:03:52 INFO - PROCESS | 1666 | 10:06:55 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 10:06:55 INFO - PROCESS | 1666 | RSS: Main: 398442496 10:06:55 INFO - PROCESS | 1666 | 10:06:55 INFO - PROCESS | 1666 | __start_tp_report 10:06:55 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 10:06:55 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 10:06:55 INFO - PROCESS | 1666 | |i|pagename|runs| 10:06:55 INFO - PROCESS | 1666 | |0;dojo.html;3472.3228970350783;3491.7111674040602;3494.0216739312987;3538.0447279234613;3575.1240945968243;4545.704529205599;5631.971840140804;5671.34779500357;5697.8005769798065;5729.169270455786;5665.8583535411635;5738.0245358289085;5751.338596061444;5822.592418530705;5849.356570777211;5081.79672813087;5121.564667003309;5159.458256883029;5162.406323272834;5252.763625636836;5238.869028274305;5250.501202385767;5267.47325267474;5294.391145018334;5319.255304257407;5079.898402031957;5101.234814777813;5157.845264642048;5198.116320225571;5201.479852014806;5174.689518628857;5182.455842136596;5185.6888586684545;5193.68837869729;5227.163653815409;5124.026434977342;5144.794208231666;5150.227465880111;5181.171012637998;5197.48025197481;5508.586855985817;5594.608377413582;5607.074832652648;5623.831285061414;5624.409437009096;6148.938510614881;6165.506759459233;6196.690165491707;6199.194104766351;6273.341299163613;6251.468625166866;6262.060690896356;6295.842603934892;6301.212348456443;6306.527010474187;5737.799177028784;5798.710064496759;5823.621464604787;5829.941700582982;5833.095870140135;6207.6275423474735;6208.689565521706;6277.435030847246;6283.968580157161;6290.99344104941;6025.518910981438;6054.576179667381;6073.240844894388;6083.117947897618;6118.235220597426;6242.250929888442;6271.216097987752;6274.905876411857;6289.5911765736;6298.433141017239;6232.657203853741;6250;6251.78118765841;6263.3736626337;6270.843228919313;6122.173506576644;6216.906746398808;6242.875142497124;6262.3111457739615;6284.277308109626;4860.95139048605;4903.01939612079;4906.31311616374;4914.0908931847725;4960.181570040975;4348.304271316638;4362.629176519968;4372.978135109304;4389.517153113155;4402.933955990663;5576.832695019156;5643.379228284885;5674.687892165888;5689.061304884731;5694.430556944355;6177.351378105234;6240.500759939194;6275.623462592259;6286.371362863677;6287.465565426898;5704.3154821421695;5706.657600543981;5709.314882214161;5712.857178570569;5731.312242530923;5235.633505654644;5338.8932221356135;5352.91970620441;5354.063038968165;5363.356397232357;5402.865398266251;6250.124982502453;6276.121343012073;6311.684415779193;6320.304766475684;6223.471004964447;6237.438630523275;6273.059041143865;6285.937140628718;6290.370962903673;6205.379462053758;6234.6570938597;6285.905711414424;6337.112804207322;6355.618662880242;6157.4458298753325;6174.289956655042;6189.04069869171;6204.937950620617;6213.130161777264;6235.719392627252;6246.063090536274;6267.185265915402;6267.373262673696;6273.247210334789;5056.924146137737;5950.375210602824;6071.028635418312;6084.543659225665;6090.786822461193;5761.193432919311;6034.426729460694;6062.757489700362;6071.150038994632;6085.604435711664;5855.648661080349;5883;5898.587098903035;5900.881982360243;5906.497947674324;6051.83147531499;6070.848228794315;6078.331383547895;6083.695815209133;6090.695465226632;5911.379305173067;5924.437178468039;5930.881382372415;5939.46544810969;5962.672053037125 10:06:55 INFO - PROCESS | 1666 | |1;ext.html;466.90195059037603;476.6234674607061;558.9547546088814;621.6611946489163;627.1909237084162;37979.81010094949;38793.836184914464;39154.21691566167;39170.412443813366;39191.80404097983;41262.58737412625;41327;41616;41637.375439368436;41663;9555.617775288998;9743.51282435879;9745.463999480025;9769.364991275579;9774.218062554979;9555;9716.61133554657;9725.902740972571;9861.06319899612;9873.950630246874;9794.9510252449;9864.802703945918;9886.752831179207;9894.901050989505;9902.504874756269;9774.071463210983;9865.704028879145;9882.308238423313;9882.901170988342;9901.950490247502;9618.903810961941;9765.560549775206;9785.951070244675;9793.755156121082;9798.412095274234;40050;40383;40394.79802600998;40402;40427;3169.7781155319135;3173.0639461358955;3349.765516413852;3354.7819391739704;3556.768810027366;28239;28300;28327.15018549447;28358.716412835813;28696;2718.198131551197;2769.6814866290433;2773.3759904021636;2787.456445993032;2851.7576006039512;467924;468076;470258;470278;470842;51646.74176629168;61742.69128654418;61930.69034654888;62138;62328;55652.16521752177;56916.43083569194;65780.34219657838;66650.66674666693;66832.33167668358;16752.581185470463;16804.83195168057;16944.74582881233;16966.15169241533;16976;11964.940175299242;12889.419976101099;12954.935225323814;12960.740785184433;13002.609921702364;19387.127579258686;19585.902070489556;19680.311189108314;19723.802761972485;19741.21035158606;7402.370798482081;7560.659770310349;7996.840063198703;8168.469049511881;8251.400808745346;958.8130314588589;960.2078285414561;962.8603852441357;1080.9297395669257;1202.6812894582943;26628.46743065128;26855.597166042524;27492.58761118585;27510;27581.03466378667;1117.3631030312645;1216.1000859364008;1217.3730528777687;1249.5126900508808;1279.0918447901909;13929.23389213603;13978.44086236539;14231.43074277038;14318;14402.423903043971;509905;510488;519506;521402;522601;58350.41649583535;58483.70758146182;58485.41514584885;58573;58672;1337.458329376604;1361.5642994241748;1440.848710885342;1460.182297913172;1497.6255936015996;28013.859930700215;28088.29779255535;28621;28670.426591467636;28743;45889;46450.53549464597;46940;46957.2956405661;47030.529694702615;360987;362820;363071;363424;363578;28147.43705125927;28605.713942860304;32446.67553324531;32911.22787195891;33011.834940825145;42427.939301516475;42536.5746342545;42568;43163.56836431596;44676.10647786961;33772.32455350929;34776.82611586926;34869.476957845145;35533;35555.466667999484;34331.485027724106;34710.305793883475;34997.30005399827;35048.824755876056;35252.294954101286;29663;34977.475337869444;35266.471002934464;35310.47034294436;36948.44577331396;26706.59940100939;27711.614419279358;30666.693333066385;30679;30774.692253077184;19818;23519.059237630987;23901.924413401786;24170.395740105934;24249.15127970583 10:06:55 INFO - PROCESS | 1666 | |2;jquery.html;361.6528132992327;363.5292296476065;367.41214057507983;369.45689835941175;375.5549673636739;20103.899480502594;20406.591868162646;20640.587188256224;20851.895740521275;20980.950952452393;20924.163033478642;20986;21690.240841570547;21696.023678934445;21706.3488095357;5172.19830926207;5869.85325366865;6059.212302400681;6091.2690477142805;6100.420460056288;5772.595918285721;6080.179175811253;6085.0263957766765;6089.482393996494;6093.78671746489;6026.337102918676;6026.63840169589;6049;6053.273607167146;6057.42454466825;5722.885542289173;5990.8502287442725;6000.039993601046;6002.519798416116;6009.248843894513;5151;5189.091908915962;5194;5196.948030519684;5199.350081239845;12967.962562994937;13523.932380338134;26089.73910260911;26996.865015674994;27028.729712702818;1853.9907300463412;2197.5824593327216;2330.475642980333;2516.993202718927;2535.530926778552;9977.950110249474;11461.255018423859;13592.524261650795;15036.473723419736;15245.923770381189;1704.548294701905;1711.341133663536;1719.6474722681821;1750.6673731990882;1811.610503741702;106416.46791766069;109460;111085.88914110836;111792;114179;11542.538298467967;11542.961133498025;11558.884411155781;11569.247998880046;11653.18427710069;13811;13895.166290022631;13940.302984850718;13978.720425591635;13980.231087290093;7178.2103968563415;8494.532800695897;8677.1756683115;8696.391252612257;8776.648934042567;8912.197902188835;8932.71229809695;9002.684906028258;9128.954355228314;9147.405418647766;4470.977645111753;4542.591166795004;4567.474740404896;4600.079984003212;4619.214733495314;1799.6940520111611;1849.1771161833124;1904.8476121910214;1915.52111972007;1939.5926855360533;403.1714826032473;415.70900369741304;818.1940788323496;969.0551712080664;988.6687959533493;9941;11297.661070167907;11760.529578816922;11947.522099116111;11987.52049918011;446.73642550894476;453.87518432431347;466.1678903157847;477.0435277269053;875.9036505984336;9359.29805264608;10573;10817.296875703054;10832.945835270773;10843.885442338667;598552;602687;604272;611065;631917;96374;114174.42912785549;116359.41820290845;122250.77749222572;124640;1009.9596016159418;1025.8563801067855;1051.085555566662;1051.8685164354304;1054.7574057966558;14410.495632652806;18120.456386308433;18546.871737769045;19036;19124.71312930335;48015;50212.24681629852;50416.49583504118;50599.74700126476;51087.74456127696;74570.62714686392;76155;76253;77747.61126194333;77959.22040779519;7823.8435231293915;8030.598470076589;8102.149274326347;8201.58992050407;8368.665253389796;9492.810143796947;12366.567170149001;13127.081104322606;13315.26766027839;13426.731465370443;10094.394336339843;11369.602063927723;11506.367149806507;11856.644300670649;11890.771511734561;11342.262752921033;11422.771544569228;11569.6529104127;11603.129765267808;11832.290062596272;11681.591144309908;11896.821547676618;11969.341686207343;12085.75828483443;12141.028717702386;8110.148434414302;8142.389320800845;8289.792755181168;8297.917020829715;8419.455299228539;10711.785764284516;11793.938545530944;11890;11897.40512974365;11926.642200733992 10:06:55 INFO - PROCESS | 1666 | |3;mootools.html;1408.7886816977446;1611.5246002429283;1629.120275051472;1912.7130930360445;2035;7991;8703.608337624806;8888.28893688505;8891.466512009276;8892.644294228223;8456.069832318455;8739.126087391258;8749.693760718375;8751.737447876558;8752.737417877457;2197.3188311623367;2442.572549803786;2497.026159797676;2526.810489213307;2527;2536;2546.363409147713;2549.5155920375164;2550.5281522918226;2556.335352808274;2433.9634905476432;2482.515909397668;2504.611785173301;2505.5364757519824;2509.987450062756;2452.301094188148;2491.9003239870385;2500.5124000819847;2518.937026574332;2519.987400063006;2470.1354525915967;2491.9626205606933;2513.258588716315;2515.5094756522485;2519.848809071443;8855.778605534848;8917.955410222972;8941.597628106685;8947.597358118901;8973.910260897437;5464.289642346509;5574.749136288877;5612.915806262909;5616.157576363537;5619.409961953977;3567.94648080279;3637.236180402119;3688.2623475304777;3700.5559332879957;3715.2012317351628;845.6194712379454;1025.5897640943608;1052.7841792432532;1058.269793842254;1069.8712857934875;11891.464884080151;11923.642290731292;11944.462499187557;11953.581624643182;11961.940190298992;4923.532264434873;4934.5312195341385;4935.3090567320605;5023.472535383805;5101.413337466165;10138.087572118544;10138.391696498235;10151.289409741396;10152.39085654863;10168.593256269814;5584.190292407578;5593.188987596775;5594.356648985337;5601.075822489325;5607.243022191952;4483.417155769795;4647.838040489877;4819.746865814844;4951.455339912607;5052.216906379517;3328.6005679318637;3895.6299151580556;3968.6229808168177;3995.780232087205;4022.034711669237;1147.6629726368687;1205.951761929513;1315.0925861155772;1352.5468967895667;1361.7140400516003;1381.7996390523317;1395.0374241773145;1424.287856071964;1426.7431862264891;1432.4628264400849;8146.159257314304;8403.789905252417;8410.537420441935;8426;8449.831003379897;989.618996686281;1205.7950148474781;1231.1443546734934;1232.2483285196024;1254.6047994881583;5676.574256930664;6172.73458940916;6300.936990630035;6347.460465860361;6362.2683391409655;10184.32766707508;10649;10663.520141593492;10674.35953842772;10681.626143084955;10132.797344053077;10450.320729152581;10520.526576303932;10543.156547476183;10561.155107591374;8154.103048664642;8304.958475207586;8347.791305217419;8351.707690230935;8361.122082181406;7120.928790712027;8017.438779285394;8076.757697269091;8092.231238032377;8140.715074972348;9340.439573625605;9361.251099911855;9453.101955314234;9473.715788526355;9557.665481708107;9267;9581;9750.902490974999;9909.851352229867;9932.453715045742;5918.822435326947;6763.086983257197;6920.5466851961655;7432;7572.734954276574;8127.187281271826;8241.629126689193;8245.134260902758;8246.422750407413;8254.463459875089;7243.927560724325;7339.559626422431;7349.191588925107;7493.325600695964;7494.325510704063;7221.097362829646;7279.053723016186;7342.816429589303;7378.077740282465;7393;7727.92272077272;7734.149243583314;7788.454808163374;7791.259830316111;7794.337481314152;6789.9336381943385;7013.333733295329;7068.5758854468895;7081.4334853210585;7087.255838137127;7137.357637812622;7138.607376594337;7150.392216661642;7157.606331651809;7181.569105853666 10:06:55 INFO - PROCESS | 1666 | |4;prototype.html;320.2713826045746;324.88141828232705;327.8393587142301;330.50919384713706;331.83242462556404;6502.414782669559;6561.311062338451;6576.967115164418;6577;6578.473722102232;6541.247756508003;6545.574537655049;6554.573952693084;6555.573887697308;6574.80275591733;4621.491635920046;4674.368960190365;4710.152172608929;4806.855794326176;4875.731834749087;3948.3287841066945;4752.881177970561;4812.614990800745;4827.51724827518;4878.4145902491755;4320.984568626371;4339.956600434002;4768.189407800664;4777.116233496814;4784.617230621542;4582.152301824157;4596;4687.484376718558;4717.292406139107;4719.197736384823;4029.879103626896;4040.272750904836;4045.696572757055;4048.4332193492933;4057.7565346079036;6105.267367915879;6160.168377269101;6207.937920620781;6247.437730604267;6307.590006649553;5705.686187259699;5709.800156994485;5714.057180565202;5715.628484148517;5715.771369145229;6876.965615171942;6879.58722476653;6884.070650462174;6889.379955803952;6892.207396149457;4377.9781101094295;4527.501974782771;4555.726656400593;4566.725996440225;4570.360149579062;7313.7074517019255;7398.70405183792;7431.256874312634;7469.701211951569;7481.289277518627;4587.4036375271;4613.953860461353;4618.884527886763;4622.722636641813;4628.490866004737;3859;4677.883052923704;4678.883027924329;4683.765811709401;4688.976555117202;5601.467860553241;5606.187102870061;5607.7476513556985;5614.101743720985;5630.324361076697;6740.595564266159;6981.79054628362;6993.300669933068;7051.1891132519395;7072.5402848814665;1605.4782195786415;1612.00861470195;1621.100289339428;1624.5288866228898;1626.2031604513704;985.2019863910256;1007.0433088565892;1024.88799652378;1037.8287582548794;1056.5984925728328;6500.219973603103;6638.634875081917;6661.100751398596;6661.4004739573675;6696.06255124283;5938.970305148533;7365.7790266292095;7369.668364923591;7380.483366164317;7398.593077380688;6637.9668101660145;6647.634380109141;6652.501062420338;6652.600843949379;6654.201495820533;3417.6069751978343;3649.5073165122367;3835.6547910688173;3887.183691424776;3895.9805200973815;7407.481476296715;7671.61641917913;7677.808054798675;7698.884516732253;7704.5762483062845;7396.630168491554;7474.40204783616;7476.887846682304;7480.251974802476;7483.513571617827;7659.9234007660325;7669.693212271558;7672.347850432776;7675.654595543326;7675.961620191863;6352.174217351715;7016.5088443808445;7313.488055836253;7326.267373262631;7327.230640782641;5849.707514624337;7168.17565979919;7226.277372262732;7229.060222171084;7262.49162558637;7244.311790379906;7520.653309277319;7656.885146722692;7666;7667.578283194478;4775.641826863069;4815.807367705253;4815.927761083516;4823.300621409806;4827.541383568555;6416.716656668592;6587.505937054644;6951.895721564079;7202.3877970373105;7218.5307954984855;5971.745933354045;6744;6990.09128813251;7003.964980175067;7027.648617569203;6924.826879328057;6992.965035174791;6994.300569942965;6999;7007.369336759716;6831.658417079226;6831.9658401707675;6839.179298484214;6843.589384636938;6845.247022827385;6755.392014718699;6968.128983877015;6991.7902462926195;7000.544964577286;7071.504994650325;5322.627416080837;5430.755615997368;6742.089817874524;6859.862802744017;6873.072135261676 10:06:55 INFO - PROCESS | 1666 | |5;yui.html;674.5817593092282;680.3502654964509;722.7109156337465;756.4440136499675;774.8217909880727;14711.779323310146;14918.04671761984;14929.253537323131;14929.477468288613;14997.10017398957;13710.451581936735;13828.446862125527;13832.377543010563;13853;13863.4454621815;707.6815433055133;887.6582515731425;914.5610107148573;915.9496227707507;916.7478943290615;897.3897749530316;907.9682211122612;909.3134683314122;909.6770646420524;913.995430022849;760.2321655128333;963.2631037256505;963.6868017894177;964.1322809471461;969.2730452160879;952.6046690623417;970.0251247496257;974.3423189347162;974.6198982396871;974.8001659659824;718.881384571545;718.9029481020048;720.1718024272129;722.869883420984;723.1936390924081;14847.70304593902;14932.104073755609;14939;14951.177685227309;14955.102693838404;12631.368431578476;12668.4299206535;12766.042546808934;12856.35718214095;13016.674583135497;9907.306488545804;10108.545115469822;10233.539490722935;10619.309744866636;10881.619143329945;3482.320947415255;3524.7708898921487;3573.535440392732;3592.1738000259825;3793.9241215175543;83937.16062839415;85385.57307213424;85671;87161.56419217927;87583;8155.959220203979;9280.350375473652;9362.485063321446;9386.342955993152;9744.464054477072;21199.258025969324;21208.681869771655;21217.469563260733;21221.89389053076;21237.256696015564;7266.418686505067;7404.629768511661;7524.247575242542;7527.2849079337375;7588.924110758932;4067.0645751477327;4124.092699606082;4138.668906487474;4191.371294305878;4216.367544868233;3558.3594952908206;3602;3605.02664280648;3612.02475331659;3614.6385361464168;566.507138789256;577.3216470646973;654.5254690349459;662.8144119646506;669.855980964095;493.5188191513246;512.8615273876107;558.8491107400979;589.9085641725453;602.8794241151787;7687.6156192190165;8023.1575684553445;8114.148014458515;8188.50868947865;8471.449355791854;551.6469459545893;565.7652273519614;583.9984426708215;600.9513399118479;603.405645439242;3686.152184997412;3735.7571757835653;3791.7914514701956;3980.721349505565;4051.513818341818;81659.59170204111;84277;84309.57845210735;84439.15560844314;84694;70945.6452717733;72346;72394.63802680952;72834.9074763879;73472;64172;64634.67682661651;65306.673466633314;69372.65313673399;70174.59650806955;8428.157184281523;8491.787705307417;8579.2707619853;8582.173411443737;8599.871001935102;47365.7631711853;48475.78810529624;48784;49535.504644953086;49797;66447.66776166088;68961.3103868955;70583;71250;71760.69435833658;10599;10701.143908487147;10787.838182427427;10992.670219893416;11584.073274138218;10477.476126193813;11954.402279886144;12348.197367171106;12368.876311236772;12412.827755142633;11296.887031129912;11373.31760094397;11517.308961462339;11764.882351176379;12025.4588543514;11286.548538058385;11399.373034483184;11563.421828908353;12046.518139274682;12421.565245216736;11072.612458563912;11297.491612877604;11415.315081095161;11427.015520186078;11510.194286399872;9580.520973951136;9707.417554946725;9723.47858245919;9926.205903527556;10041.297109202285;7649.349805266615;8912.598933048159;9482.857757133786;9545.809083818145;9613.230941524802 10:06:55 INFO - PROCESS | 1666 | __end_tp_report 10:06:55 INFO - PROCESS | 1666 | __start_cc_report 10:06:55 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2287 10:06:55 INFO - PROCESS | 1666 | __end_cc_report 10:06:55 INFO - PROCESS | 1666 | __startTimestamp1457633215815__endTimestamp 10:06:55 INFO - PROCESS | 1666 | 10:06:55 INFO - PROCESS | 1666 | ------- Summary: start ------- 10:06:55 INFO - PROCESS | 1666 | Number of tests: 6 10:06:55 INFO - PROCESS | 1666 | 10:06:55 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5718.51 Median:5906.50 stddev:634.99 (10.8%) stddev-sans-first:612.00 10:06:55 INFO - PROCESS | 1666 | Values: 3472.3 3491.7 3494.0 3538.0 3575.1 4545.7 5632.0 5671.3 5697.8 5729.2 5665.9 5738.0 5751.3 5822.6 5849.4 5081.8 5121.6 5159.5 5162.4 5252.8 5238.9 5250.5 5267.5 5294.4 5319.3 5079.9 5101.2 5157.8 5198.1 5201.5 5174.7 5182.5 5185.7 5193.7 5227.2 5124.0 5144.8 5150.2 5181.2 5197.5 5508.6 5594.6 5607.1 5623.8 5624.4 6148.9 6165.5 6196.7 6199.2 6273.3 6251.5 6262.1 6295.8 6301.2 6306.5 5737.8 5798.7 5823.6 5829.9 5833.1 6207.6 6208.7 6277.4 6284.0 6291.0 6025.5 6054.6 6073.2 6083.1 6118.2 6242.3 6271.2 6274.9 6289.6 6298.4 6232.7 6250.0 6251.8 6263.4 6270.8 6122.2 6216.9 6242.9 6262.3 6284.3 4861.0 4903.0 4906.3 4914.1 4960.2 4348.3 4362.6 4373.0 4389.5 4402.9 5576.8 5643.4 5674.7 5689.1 5694.4 6177.4 6240.5 6275.6 6286.4 6287.5 5704.3 5706.7 5709.3 5712.9 5731.3 5235.6 5338.9 5352.9 5354.1 5363.4 5402.9 6250.1 6276.1 6311.7 6320.3 6223.5 6237.4 6273.1 6285.9 6290.4 6205.4 6234.7 6285.9 6337.1 6355.6 6157.4 6174.3 6189.0 6204.9 6213.1 6235.7 6246.1 6267.2 6267.4 6273.2 5056.9 5950.4 6071.0 6084.5 6090.8 5761.2 6034.4 6062.8 6071.2 6085.6 5855.6 5883.0 5898.6 5900.9 5906.5 6051.8 6070.8 6078.3 6083.7 6090.7 5911.4 5924.4 5930.9 5939.5 5962.7 10:06:55 INFO - PROCESS | 1666 | 10:06:55 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:59515.56 Median:200263.22 stddev:120680.32 (60.3%) stddev-sans-first:120937.43 10:06:55 INFO - PROCESS | 1666 | Values: 466.9 476.6 559.0 621.7 627.2 37979.8 38793.8 39154.2 39170.4 39191.8 41262.6 41327.0 41616.0 41637.4 41663.0 9555.6 9743.5 9745.5 9769.4 9774.2 9555.0 9716.6 9725.9 9861.1 9874.0 9795.0 9864.8 9886.8 9894.9 9902.5 9774.1 9865.7 9882.3 9882.9 9902.0 9618.9 9765.6 9786.0 9793.8 9798.4 40050.0 40383.0 40394.8 40402.0 40427.0 3169.8 3173.1 3349.8 3354.8 3556.8 28239.0 28300.0 28327.2 28358.7 28696.0 2718.2 2769.7 2773.4 2787.5 2851.8 467924.0 468076.0 470258.0 470278.0 470842.0 51646.7 61742.7 61930.7 62138.0 62328.0 55652.2 56916.4 65780.3 66650.7 66832.3 16752.6 16804.8 16944.7 16966.2 16976.0 11964.9 12889.4 12954.9 12960.7 13002.6 19387.1 19585.9 19680.3 19723.8 19741.2 7402.4 7560.7 7996.8 8168.5 8251.4 958.8 960.2 962.9 1080.9 1202.7 26628.5 26855.6 27492.6 27510.0 27581.0 1117.4 1216.1 1217.4 1249.5 1279.1 13929.2 13978.4 14231.4 14318.0 14402.4 509905.0 510488.0 519506.0 521402.0 522601.0 58350.4 58483.7 58485.4 58573.0 58672.0 1337.5 1361.6 1440.8 1460.2 1497.6 28013.9 28088.3 28621.0 28670.4 28743.0 45889.0 46450.5 46940.0 46957.3 47030.5 360987.0 362820.0 363071.0 363424.0 363578.0 28147.4 28605.7 32446.7 32911.2 33011.8 42427.9 42536.6 42568.0 43163.6 44676.1 33772.3 34776.8 34869.5 35533.0 35555.5 34331.5 34710.3 34997.3 35048.8 35252.3 29663.0 34977.5 35266.5 35310.5 36948.4 26706.6 27711.6 30666.7 30679.0 30774.7 19818.0 23519.1 23901.9 24170.4 24249.2 10:06:55 INFO - PROCESS | 1666 | 10:06:55 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34389.64 Median:2526.26 stddev:101221.59 (4006.8%) stddev-sans-first:101473.30 10:06:55 INFO - PROCESS | 1666 | Values: 361.7 363.5 367.4 369.5 375.6 20103.9 20406.6 20640.6 20851.9 20981.0 20924.2 20986.0 21690.2 21696.0 21706.3 5172.2 5869.9 6059.2 6091.3 6100.4 5772.6 6080.2 6085.0 6089.5 6093.8 6026.3 6026.6 6049.0 6053.3 6057.4 5722.9 5990.9 6000.0 6002.5 6009.2 5151.0 5189.1 5194.0 5196.9 5199.4 12968.0 13523.9 26089.7 26996.9 27028.7 1854.0 2197.6 2330.5 2517.0 2535.5 9978.0 11461.3 13592.5 15036.5 15245.9 1704.5 1711.3 1719.6 1750.7 1811.6 106416.5 109460.0 111085.9 111792.0 114179.0 11542.5 11543.0 11558.9 11569.2 11653.2 13811.0 13895.2 13940.3 13978.7 13980.2 7178.2 8494.5 8677.2 8696.4 8776.6 8912.2 8932.7 9002.7 9129.0 9147.4 4471.0 4542.6 4567.5 4600.1 4619.2 1799.7 1849.2 1904.8 1915.5 1939.6 403.2 415.7 818.2 969.1 988.7 9941.0 11297.7 11760.5 11947.5 11987.5 446.7 453.9 466.2 477.0 875.9 9359.3 10573.0 10817.3 10832.9 10843.9 598552.0 602687.0 604272.0 611065.0 631917.0 96374.0 114174.4 116359.4 122250.8 124640.0 1010.0 1025.9 1051.1 1051.9 1054.8 14410.5 18120.5 18546.9 19036.0 19124.7 48015.0 50212.2 50416.5 50599.7 51087.7 74570.6 76155.0 76253.0 77747.6 77959.2 7823.8 8030.6 8102.1 8201.6 8368.7 9492.8 12366.6 13127.1 13315.3 13426.7 10094.4 11369.6 11506.4 11856.6 11890.8 11342.3 11422.8 11569.7 11603.1 11832.3 11681.6 11896.8 11969.3 12085.8 12141.0 8110.1 8142.4 8289.8 8297.9 8419.5 10711.8 11793.9 11890.0 11897.4 11926.6 10:06:55 INFO - PROCESS | 1666 | 10:06:55 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:6070.86 Median:5588.69 stddev:3153.31 (56.4%) stddev-sans-first:3142.68 10:06:55 INFO - PROCESS | 1666 | Values: 1408.8 1611.5 1629.1 1912.7 2035.0 7991.0 8703.6 8888.3 8891.5 8892.6 8456.1 8739.1 8749.7 8751.7 8752.7 2197.3 2442.6 2497.0 2526.8 2527.0 2536.0 2546.4 2549.5 2550.5 2556.3 2434.0 2482.5 2504.6 2505.5 2510.0 2452.3 2491.9 2500.5 2518.9 2520.0 2470.1 2492.0 2513.3 2515.5 2519.8 8855.8 8918.0 8941.6 8947.6 8973.9 5464.3 5574.7 5612.9 5616.2 5619.4 3567.9 3637.2 3688.3 3700.6 3715.2 845.6 1025.6 1052.8 1058.3 1069.9 11891.5 11923.6 11944.5 11953.6 11961.9 4923.5 4934.5 4935.3 5023.5 5101.4 10138.1 10138.4 10151.3 10152.4 10168.6 5584.2 5593.2 5594.4 5601.1 5607.2 4483.4 4647.8 4819.7 4951.5 5052.2 3328.6 3895.6 3968.6 3995.8 4022.0 1147.7 1206.0 1315.1 1352.5 1361.7 1381.8 1395.0 1424.3 1426.7 1432.5 8146.2 8403.8 8410.5 8426.0 8449.8 989.6 1205.8 1231.1 1232.2 1254.6 5676.6 6172.7 6300.9 6347.5 6362.3 10184.3 10649.0 10663.5 10674.4 10681.6 10132.8 10450.3 10520.5 10543.2 10561.2 8154.1 8305.0 8347.8 8351.7 8361.1 7120.9 8017.4 8076.8 8092.2 8140.7 9340.4 9361.3 9453.1 9473.7 9557.7 9267.0 9581.0 9750.9 9909.9 9932.5 5918.8 6763.1 6920.5 7432.0 7572.7 8127.2 8241.6 8245.1 8246.4 8254.5 7243.9 7339.6 7349.2 7493.3 7494.3 7221.1 7279.1 7342.8 7378.1 7393.0 7727.9 7734.1 7788.5 7791.3 7794.3 6789.9 7013.3 7068.6 7081.4 7087.3 7137.4 7138.6 7150.4 7157.6 7181.6 10:06:55 INFO - PROCESS | 1666 | 10:06:55 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5697.69 Median:6555.07 stddev:1840.99 (28.1%) stddev-sans-first:1801.37 10:06:55 INFO - PROCESS | 1666 | Values: 320.3 324.9 327.8 330.5 331.8 6502.4 6561.3 6577.0 6577.0 6578.5 6541.2 6545.6 6554.6 6555.6 6574.8 4621.5 4674.4 4710.2 4806.9 4875.7 3948.3 4752.9 4812.6 4827.5 4878.4 4321.0 4340.0 4768.2 4777.1 4784.6 4582.2 4596.0 4687.5 4717.3 4719.2 4029.9 4040.3 4045.7 4048.4 4057.8 6105.3 6160.2 6207.9 6247.4 6307.6 5705.7 5709.8 5714.1 5715.6 5715.8 6877.0 6879.6 6884.1 6889.4 6892.2 4378.0 4527.5 4555.7 4566.7 4570.4 7313.7 7398.7 7431.3 7469.7 7481.3 4587.4 4614.0 4618.9 4622.7 4628.5 3859.0 4677.9 4678.9 4683.8 4689.0 5601.5 5606.2 5607.7 5614.1 5630.3 6740.6 6981.8 6993.3 7051.2 7072.5 1605.5 1612.0 1621.1 1624.5 1626.2 985.2 1007.0 1024.9 1037.8 1056.6 6500.2 6638.6 6661.1 6661.4 6696.1 5939.0 7365.8 7369.7 7380.5 7398.6 6638.0 6647.6 6652.5 6652.6 6654.2 3417.6 3649.5 3835.7 3887.2 3896.0 7407.5 7671.6 7677.8 7698.9 7704.6 7396.6 7474.4 7476.9 7480.3 7483.5 7659.9 7669.7 7672.3 7675.7 7676.0 6352.2 7016.5 7313.5 7326.3 7327.2 5849.7 7168.2 7226.3 7229.1 7262.5 7244.3 7520.7 7656.9 7666.0 7667.6 4775.6 4815.8 4815.9 4823.3 4827.5 6416.7 6587.5 6951.9 7202.4 7218.5 5971.7 6744.0 6990.1 7004.0 7027.6 6924.8 6993.0 6994.3 6999.0 7007.4 6831.7 6832.0 6839.2 6843.6 6845.2 6755.4 6968.1 6991.8 7000.5 7071.5 5322.6 5430.8 6742.1 6859.9 6873.1 10:06:55 INFO - PROCESS | 1666 | 10:06:55 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17979.09 Median:603.14 stddev:24951.18 (4136.9%) stddev-sans-first:24987.34 10:06:55 INFO - PROCESS | 1666 | Values: 674.6 680.4 722.7 756.4 774.8 14711.8 14918.0 14929.3 14929.5 14997.1 13710.5 13828.4 13832.4 13853.0 13863.4 707.7 887.7 914.6 915.9 916.7 897.4 908.0 909.3 909.7 914.0 760.2 963.3 963.7 964.1 969.3 952.6 970.0 974.3 974.6 974.8 718.9 718.9 720.2 722.9 723.2 14847.7 14932.1 14939.0 14951.2 14955.1 12631.4 12668.4 12766.0 12856.4 13016.7 9907.3 10108.5 10233.5 10619.3 10881.6 3482.3 3524.8 3573.5 3592.2 3793.9 83937.2 85385.6 85671.0 87161.6 87583.0 8156.0 9280.4 9362.5 9386.3 9744.5 21199.3 21208.7 21217.5 21221.9 21237.3 7266.4 7404.6 7524.2 7527.3 7588.9 4067.1 4124.1 4138.7 4191.4 4216.4 3558.4 3602.0 3605.0 3612.0 3614.6 566.5 577.3 654.5 662.8 669.9 493.5 512.9 558.8 589.9 602.9 7687.6 8023.2 8114.1 8188.5 8471.4 551.6 565.8 584.0 601.0 603.4 3686.2 3735.8 3791.8 3980.7 4051.5 81659.6 84277.0 84309.6 84439.2 84694.0 70945.6 72346.0 72394.6 72834.9 73472.0 64172.0 64634.7 65306.7 69372.7 70174.6 8428.2 8491.8 8579.3 8582.2 8599.9 47365.8 48475.8 48784.0 49535.5 49797.0 66447.7 68961.3 70583.0 71250.0 71760.7 10599.0 10701.1 10787.8 10992.7 11584.1 10477.5 11954.4 12348.2 12368.9 12412.8 11296.9 11373.3 11517.3 11764.9 12025.5 11286.5 11399.4 11563.4 12046.5 12421.6 11072.6 11297.5 11415.3 11427.0 11510.2 9580.5 9707.4 9723.5 9926.2 10041.3 7649.3 8912.6 9482.9 9545.8 9613.2 10:06:55 INFO - PROCESS | 1666 | -------- Summary: end -------- 10:06:55 INFO - PROCESS | 1666 | 10:06:56 INFO - TEST-INFO | 1666: exit 0 10:06:56 INFO - TEST-OK | dromaeo_css | took 1113797ms 10:06:56 INFO - TEST-START | kraken 10:06:56 INFO - operating with platform_type : mac_ 10:06:56 INFO - Initialising browser for kraken test... 10:06:56 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpXjGTg7/profile http://localhost:49270/getInfo.html) 10:06:58 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 10:06:58 INFO - PROCESS | 1673 | colorDepth:24 10:06:58 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 10:06:58 INFO - PROCESS | 1673 | __metrics 10:06:58 INFO - PROCESS | 1673 | 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" 10:06:58 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:06:59 INFO - TEST-INFO | 1673: exit 0 10:06:59 INFO - Browser initialized. 10:06:59 INFO - Running cycle 1/1 for kraken test... 10:06:59 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpXjGTg7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:07:11 INFO - PROCESS | 1675 | RSS: Main: 243257344 10:07:11 INFO - PROCESS | 1675 | 10:07:13 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 10:07:14 INFO - PROCESS | 1675 | RSS: Main: 288792576 10:07:14 INFO - PROCESS | 1675 | 10:07:17 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 10:07:17 INFO - PROCESS | 1675 | RSS: Main: 299737088 10:07:17 INFO - PROCESS | 1675 | 10:07:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 10:07:21 INFO - PROCESS | 1675 | RSS: Main: 285388800 10:07:21 INFO - PROCESS | 1675 | 10:07:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 10:07:24 INFO - PROCESS | 1675 | RSS: Main: 289693696 10:07:24 INFO - PROCESS | 1675 | 10:07:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:07:26 INFO - PROCESS | 1675 | RSS: Main: 290009088 10:07:26 INFO - PROCESS | 1675 | 10:07:29 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 10:07:30 INFO - PROCESS | 1675 | RSS: Main: 291295232 10:07:30 INFO - PROCESS | 1675 | 10:07:32 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 10:07:33 INFO - PROCESS | 1675 | RSS: Main: 291663872 10:07:33 INFO - PROCESS | 1675 | 10:07:36 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 10:07:37 INFO - PROCESS | 1675 | RSS: Main: 292278272 10:07:37 INFO - PROCESS | 1675 | 10:07:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:07:39 INFO - PROCESS | 1675 | RSS: Main: 325173248 10:07:39 INFO - PROCESS | 1675 | 10:07:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:07:41 INFO - PROCESS | 1675 | RSS: Main: 324448256 10:07:41 INFO - PROCESS | 1675 | 10:07:43 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:07:44 INFO - PROCESS | 1675 | RSS: Main: 329912320 10:07:44 INFO - PROCESS | 1675 | 10:07:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:07:47 INFO - PROCESS | 1675 | RSS: Main: 332902400 10:07:47 INFO - PROCESS | 1675 | 10:07:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:50 INFO - PROCESS | 1675 | RSS: Main: 333475840 10:07:50 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 10:07:52 INFO - PROCESS | 1675 | RSS: Main: 392503296 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | __start_tp_report 10:07:52 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 10:07:52 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 10:07:52 INFO - PROCESS | 1675 | |i|pagename|runs| 10:07:52 INFO - PROCESS | 1675 | |0;ai-astar;110;111;111;111;112;113;116;111;112;111 10:07:52 INFO - PROCESS | 1675 | |1;audio-beat-detection;155;120;146;157;139;167;165;144;157;115 10:07:52 INFO - PROCESS | 1675 | |2;audio-dft;182;183;186;185;184;186;185;186;181;185 10:07:52 INFO - PROCESS | 1675 | |3;audio-fft;71;75;85;79;56;57;58;78;75;55 10:07:52 INFO - PROCESS | 1675 | |4;audio-oscillator;95;95;129;81;78;83;117;78;80;115 10:07:52 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;89;86;91;87;90;86;91;87;89;87 10:07:52 INFO - PROCESS | 1675 | |6;imaging-darkroom;125;93;95;95;94;94;95;95;94;94 10:07:52 INFO - PROCESS | 1675 | |7;imaging-desaturate;83;87;84;107;136;74;144;143;131;85 10:07:52 INFO - PROCESS | 1675 | |8;json-parse-financial;44;52;54;49;51;54;54;64;55;53 10:07:52 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;62;72;67;74;71;75;91;96;99;99 10:07:52 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;83;83;83;81;83;82;82;81;84;83 10:07:52 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;123;132;124;131;119;120;124;124;118;121 10:07:52 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;138;126;138;138;137;126;136;136;136;139 10:07:52 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;66;67;67;68;68;73;92;92;92;74 10:07:52 INFO - PROCESS | 1675 | __end_tp_report 10:07:52 INFO - PROCESS | 1675 | __start_cc_report 10:07:52 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1188 10:07:52 INFO - PROCESS | 1675 | __end_cc_report 10:07:52 INFO - PROCESS | 1675 | __startTimestamp1457633272714__endTimestamp 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | ------- Summary: start ------- 10:07:52 INFO - PROCESS | 1675 | Number of tests: 14 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:111.80 Median:111.50 stddev:1.69 (1.5%) stddev-sans-first:1.66 10:07:52 INFO - PROCESS | 1675 | Values: 110.0 111.0 111.0 111.0 112.0 113.0 116.0 111.0 112.0 111.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:146.50 Median:156.00 stddev:17.68 (11.3%) stddev-sans-first:18.48 10:07:52 INFO - PROCESS | 1675 | Values: 155.0 120.0 146.0 157.0 139.0 167.0 165.0 144.0 157.0 115.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:184.30 Median:185.00 stddev:1.77 (1.0%) stddev-sans-first:1.67 10:07:52 INFO - PROCESS | 1675 | Values: 182.0 183.0 186.0 185.0 184.0 186.0 185.0 186.0 181.0 185.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:68.90 Median:75.00 stddev:11.27 (15.0%) stddev-sans-first:11.93 10:07:52 INFO - PROCESS | 1675 | Values: 71.0 75.0 85.0 79.0 56.0 57.0 58.0 78.0 75.0 55.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:80.50 stddev:18.81 (23.4%) stddev-sans-first:19.95 10:07:52 INFO - PROCESS | 1675 | Values: 95.0 95.0 129.0 81.0 78.0 83.0 117.0 78.0 80.0 115.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.00 stddev:1.95 (2.2%) stddev-sans-first:2.05 10:07:52 INFO - PROCESS | 1675 | Values: 89.0 86.0 91.0 87.0 90.0 86.0 91.0 87.0 89.0 87.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:97.40 Median:94.50 stddev:9.72 (10.3%) stddev-sans-first:0.71 10:07:52 INFO - PROCESS | 1675 | Values: 125.0 93.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:107.40 Median:78.50 stddev:28.20 (35.9%) stddev-sans-first:28.50 10:07:52 INFO - PROCESS | 1675 | Values: 83.0 87.0 84.0 107.0 136.0 74.0 144.0 143.0 131.0 85.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:54.00 stddev:5.06 (9.4%) stddev-sans-first:4.18 10:07:52 INFO - PROCESS | 1675 | Values: 44.0 52.0 54.0 49.0 51.0 54.0 54.0 64.0 55.0 53.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:80.60 Median:83.00 stddev:14.12 (17.0%) stddev-sans-first:13.28 10:07:52 INFO - PROCESS | 1675 | Values: 62.0 72.0 67.0 74.0 71.0 75.0 91.0 96.0 99.0 99.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:0.97 (1.2%) stddev-sans-first:1.01 10:07:52 INFO - PROCESS | 1675 | Values: 83.0 83.0 83.0 81.0 83.0 82.0 82.0 81.0 84.0 83.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:124.00 stddev:4.70 (3.8%) stddev-sans-first:4.97 10:07:52 INFO - PROCESS | 1675 | Values: 123.0 132.0 124.0 131.0 119.0 120.0 124.0 124.0 118.0 121.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.00 Median:137.50 stddev:4.85 (3.5%) stddev-sans-first:5.02 10:07:52 INFO - PROCESS | 1675 | Values: 138.0 126.0 138.0 138.0 137.0 126.0 136.0 136.0 136.0 139.0 10:07:52 INFO - PROCESS | 1675 | 10:07:52 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.90 Median:73.50 stddev:11.41 (15.5%) stddev-sans-first:11.52 10:07:52 INFO - PROCESS | 1675 | Values: 66.0 67.0 67.0 68.0 68.0 73.0 92.0 92.0 92.0 74.0 10:07:52 INFO - PROCESS | 1675 | -------- Summary: end -------- 10:07:52 INFO - PROCESS | 1675 | 10:07:53 INFO - TEST-INFO | 1675: exit 0 10:07:53 INFO - TEST-OK | kraken | took 57078ms 10:07:53 INFO - SUITE-END | took 1170s 10:07:53 INFO - Completed test suite (00:19:31) 10:07:53 INFO - javascript benchmark 10:07:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3472.3228970350783, 3491.7111674040602, 3494.0216739312987, 3538.0447279234613, 3575.1240945968243, 4545.704529205599, 5631.971840140804, 5671.34779500357, 5697.8005769798065, 5729.169270455786, 5665.8583535411635, 5738.0245358289085, 5751.338596061444, 5822.592418530705, 5849.356570777211, 5081.79672813087, 5121.564667003309, 5159.458256883029, 5162.406323272834, 5252.763625636836, 5238.869028274305, 5250.501202385767, 5267.47325267474, 5294.391145018334, 5319.255304257407, 5079.898402031957, 5101.234814777813, 5157.845264642048, 5198.116320225571, 5201.479852014806, 5174.689518628857, 5182.455842136596, 5185.6888586684545, 5193.68837869729, 5227.163653815409, 5124.026434977342, 5144.794208231666, 5150.227465880111, 5181.171012637998, 5197.48025197481, 5508.586855985817, 5594.608377413582, 5607.074832652648, 5623.831285061414, 5624.409437009096, 6148.938510614881, 6165.506759459233, 6196.690165491707, 6199.194104766351, 6273.341299163613, 6251.468625166866, 6262.060690896356, 6295.842603934892, 6301.212348456443, 6306.527010474187, 5737.799177028784, 5798.710064496759, 5823.621464604787, 5829.941700582982, 5833.095870140135, 6207.6275423474735, 6208.689565521706, 6277.435030847246, 6283.968580157161, 6290.99344104941, 6025.518910981438, 6054.576179667381, 6073.240844894388, 6083.117947897618, 6118.235220597426, 6242.250929888442, 6271.216097987752, 6274.905876411857, 6289.5911765736, 6298.433141017239, 6232.657203853741, 6250.0, 6251.78118765841, 6263.3736626337, 6270.843228919313, 6122.173506576644, 6216.906746398808, 6242.875142497124, 6262.3111457739615, 6284.277308109626, 4860.95139048605, 4903.01939612079, 4906.31311616374, 4914.0908931847725, 4960.181570040975, 4348.304271316638, 4362.629176519968, 4372.978135109304, 4389.517153113155, 4402.933955990663, 5576.832695019156, 5643.379228284885, 5674.687892165888, 5689.061304884731, 5694.430556944355, 6177.351378105234, 6240.500759939194, 6275.623462592259, 6286.371362863677, 6287.465565426898, 5704.3154821421695, 5706.657600543981, 5709.314882214161, 5712.857178570569, 5731.312242530923, 5235.633505654644, 5338.8932221356135, 5352.91970620441, 5354.063038968165, 5363.356397232357, 5402.865398266251, 6250.124982502453, 6276.121343012073, 6311.684415779193, 6320.304766475684, 6223.471004964447, 6237.438630523275, 6273.059041143865, 6285.937140628718, 6290.370962903673, 6205.379462053758, 6234.6570938597, 6285.905711414424, 6337.112804207322, 6355.618662880242, 6157.4458298753325, 6174.289956655042, 6189.04069869171, 6204.937950620617, 6213.130161777264, 6235.719392627252, 6246.063090536274, 6267.185265915402, 6267.373262673696, 6273.247210334789, 5056.924146137737, 5950.375210602824, 6071.028635418312, 6084.543659225665, 6090.786822461193, 5761.193432919311, 6034.426729460694, 6062.757489700362, 6071.150038994632, 6085.604435711664, 5855.648661080349, 5883.0, 5898.587098903035, 5900.881982360243, 5906.497947674324, 6051.83147531499, 6070.848228794315, 6078.331383547895, 6083.695815209133, 6090.695465226632, 5911.379305173067, 5924.437178468039, 5930.881382372415, 5939.46544810969, 5962.672053037125], "unit": "score", "name": "dojo.html", "value": 5679.514733297237}, {"lowerIsBetter": false, "replicates": [466.90195059037603, 476.6234674607061, 558.9547546088814, 621.6611946489163, 627.1909237084162, 37979.81010094949, 38793.836184914464, 39154.21691566167, 39170.412443813366, 39191.80404097983, 41262.58737412625, 41327.0, 41616.0, 41637.375439368436, 41663.0, 9555.617775288998, 9743.51282435879, 9745.463999480025, 9769.364991275579, 9774.218062554979, 9555.0, 9716.61133554657, 9725.902740972571, 9861.06319899612, 9873.950630246874, 9794.9510252449, 9864.802703945918, 9886.752831179207, 9894.901050989505, 9902.504874756269, 9774.071463210983, 9865.704028879145, 9882.308238423313, 9882.901170988342, 9901.950490247502, 9618.903810961941, 9765.560549775206, 9785.951070244675, 9793.755156121082, 9798.412095274234, 40050.0, 40383.0, 40394.79802600998, 40402.0, 40427.0, 3169.7781155319135, 3173.0639461358955, 3349.765516413852, 3354.7819391739704, 3556.768810027366, 28239.0, 28300.0, 28327.15018549447, 28358.716412835813, 28696.0, 2718.198131551197, 2769.6814866290433, 2773.3759904021636, 2787.456445993032, 2851.7576006039512, 467924.0, 468076.0, 470258.0, 470278.0, 470842.0, 51646.74176629168, 61742.69128654418, 61930.69034654888, 62138.0, 62328.0, 55652.16521752177, 56916.43083569194, 65780.34219657838, 66650.66674666693, 66832.33167668358, 16752.581185470463, 16804.83195168057, 16944.74582881233, 16966.15169241533, 16976.0, 11964.940175299242, 12889.419976101099, 12954.935225323814, 12960.740785184433, 13002.609921702364, 19387.127579258686, 19585.902070489556, 19680.311189108314, 19723.802761972485, 19741.21035158606, 7402.370798482081, 7560.659770310349, 7996.840063198703, 8168.469049511881, 8251.400808745346, 958.8130314588589, 960.2078285414561, 962.8603852441357, 1080.9297395669257, 1202.6812894582943, 26628.46743065128, 26855.597166042524, 27492.58761118585, 27510.0, 27581.03466378667, 1117.3631030312645, 1216.1000859364008, 1217.3730528777687, 1249.5126900508808, 1279.0918447901909, 13929.23389213603, 13978.44086236539, 14231.43074277038, 14318.0, 14402.423903043971, 509905.0, 510488.0, 519506.0, 521402.0, 522601.0, 58350.41649583535, 58483.70758146182, 58485.41514584885, 58573.0, 58672.0, 1337.458329376604, 1361.5642994241748, 1440.848710885342, 1460.182297913172, 1497.6255936015996, 28013.859930700215, 28088.29779255535, 28621.0, 28670.426591467636, 28743.0, 45889.0, 46450.53549464597, 46940.0, 46957.2956405661, 47030.529694702615, 360987.0, 362820.0, 363071.0, 363424.0, 363578.0, 28147.43705125927, 28605.713942860304, 32446.67553324531, 32911.22787195891, 33011.834940825145, 42427.939301516475, 42536.5746342545, 42568.0, 43163.56836431596, 44676.10647786961, 33772.32455350929, 34776.82611586926, 34869.476957845145, 35533.0, 35555.466667999484, 34331.485027724106, 34710.305793883475, 34997.30005399827, 35048.824755876056, 35252.294954101286, 29663.0, 34977.475337869444, 35266.471002934464, 35310.47034294436, 36948.44577331396, 26706.59940100939, 27711.614419279358, 30666.693333066385, 30679.0, 30774.692253077184, 19818.0, 23519.059237630987, 23901.924413401786, 24170.395740105934, 24249.15127970583], "unit": "score", "name": "ext.html", "value": 19292.689854133965}, {"lowerIsBetter": false, "replicates": [361.6528132992327, 363.5292296476065, 367.41214057507983, 369.45689835941175, 375.5549673636739, 20103.899480502594, 20406.591868162646, 20640.587188256224, 20851.895740521275, 20980.950952452393, 20924.163033478642, 20986.0, 21690.240841570547, 21696.023678934445, 21706.3488095357, 5172.19830926207, 5869.85325366865, 6059.212302400681, 6091.2690477142805, 6100.420460056288, 5772.595918285721, 6080.179175811253, 6085.0263957766765, 6089.482393996494, 6093.78671746489, 6026.337102918676, 6026.63840169589, 6049.0, 6053.273607167146, 6057.42454466825, 5722.885542289173, 5990.8502287442725, 6000.039993601046, 6002.519798416116, 6009.248843894513, 5151.0, 5189.091908915962, 5194.0, 5196.948030519684, 5199.350081239845, 12967.962562994937, 13523.932380338134, 26089.73910260911, 26996.865015674994, 27028.729712702818, 1853.9907300463412, 2197.5824593327216, 2330.475642980333, 2516.993202718927, 2535.530926778552, 9977.950110249474, 11461.255018423859, 13592.524261650795, 15036.473723419736, 15245.923770381189, 1704.548294701905, 1711.341133663536, 1719.6474722681821, 1750.6673731990882, 1811.610503741702, 106416.46791766069, 109460.0, 111085.88914110836, 111792.0, 114179.0, 11542.538298467967, 11542.961133498025, 11558.884411155781, 11569.247998880046, 11653.18427710069, 13811.0, 13895.166290022631, 13940.302984850718, 13978.720425591635, 13980.231087290093, 7178.2103968563415, 8494.532800695897, 8677.1756683115, 8696.391252612257, 8776.648934042567, 8912.197902188835, 8932.71229809695, 9002.684906028258, 9128.954355228314, 9147.405418647766, 4470.977645111753, 4542.591166795004, 4567.474740404896, 4600.079984003212, 4619.214733495314, 1799.6940520111611, 1849.1771161833124, 1904.8476121910214, 1915.52111972007, 1939.5926855360533, 403.1714826032473, 415.70900369741304, 818.1940788323496, 969.0551712080664, 988.6687959533493, 9941.0, 11297.661070167907, 11760.529578816922, 11947.522099116111, 11987.52049918011, 446.73642550894476, 453.87518432431347, 466.1678903157847, 477.0435277269053, 875.9036505984336, 9359.29805264608, 10573.0, 10817.296875703054, 10832.945835270773, 10843.885442338667, 598552.0, 602687.0, 604272.0, 611065.0, 631917.0, 96374.0, 114174.42912785549, 116359.41820290845, 122250.77749222572, 124640.0, 1009.9596016159418, 1025.8563801067855, 1051.085555566662, 1051.8685164354304, 1054.7574057966558, 14410.495632652806, 18120.456386308433, 18546.871737769045, 19036.0, 19124.71312930335, 48015.0, 50212.24681629852, 50416.49583504118, 50599.74700126476, 51087.74456127696, 74570.62714686392, 76155.0, 76253.0, 77747.61126194333, 77959.22040779519, 7823.8435231293915, 8030.598470076589, 8102.149274326347, 8201.58992050407, 8368.665253389796, 9492.810143796947, 12366.567170149001, 13127.081104322606, 13315.26766027839, 13426.731465370443, 10094.394336339843, 11369.602063927723, 11506.367149806507, 11856.644300670649, 11890.771511734561, 11342.262752921033, 11422.771544569228, 11569.6529104127, 11603.129765267808, 11832.290062596272, 11681.591144309908, 11896.821547676618, 11969.341686207343, 12085.75828483443, 12141.028717702386, 8110.148434414302, 8142.389320800845, 8289.792755181168, 8297.917020829715, 8419.455299228539, 10711.785764284516, 11793.938545530944, 11890.0, 11897.40512974365, 11926.642200733992], "unit": "score", "name": "jquery.html", "value": 9190.935619658703}, {"lowerIsBetter": false, "replicates": [1408.7886816977446, 1611.5246002429283, 1629.120275051472, 1912.7130930360445, 2035.0, 7991.0, 8703.608337624806, 8888.28893688505, 8891.466512009276, 8892.644294228223, 8456.069832318455, 8739.126087391258, 8749.693760718375, 8751.737447876558, 8752.737417877457, 2197.3188311623367, 2442.572549803786, 2497.026159797676, 2526.810489213307, 2527.0, 2536.0, 2546.363409147713, 2549.5155920375164, 2550.5281522918226, 2556.335352808274, 2433.9634905476432, 2482.515909397668, 2504.611785173301, 2505.5364757519824, 2509.987450062756, 2452.301094188148, 2491.9003239870385, 2500.5124000819847, 2518.937026574332, 2519.987400063006, 2470.1354525915967, 2491.9626205606933, 2513.258588716315, 2515.5094756522485, 2519.848809071443, 8855.778605534848, 8917.955410222972, 8941.597628106685, 8947.597358118901, 8973.910260897437, 5464.289642346509, 5574.749136288877, 5612.915806262909, 5616.157576363537, 5619.409961953977, 3567.94648080279, 3637.236180402119, 3688.2623475304777, 3700.5559332879957, 3715.2012317351628, 845.6194712379454, 1025.5897640943608, 1052.7841792432532, 1058.269793842254, 1069.8712857934875, 11891.464884080151, 11923.642290731292, 11944.462499187557, 11953.581624643182, 11961.940190298992, 4923.532264434873, 4934.5312195341385, 4935.3090567320605, 5023.472535383805, 5101.413337466165, 10138.087572118544, 10138.391696498235, 10151.289409741396, 10152.39085654863, 10168.593256269814, 5584.190292407578, 5593.188987596775, 5594.356648985337, 5601.075822489325, 5607.243022191952, 4483.417155769795, 4647.838040489877, 4819.746865814844, 4951.455339912607, 5052.216906379517, 3328.6005679318637, 3895.6299151580556, 3968.6229808168177, 3995.780232087205, 4022.034711669237, 1147.6629726368687, 1205.951761929513, 1315.0925861155772, 1352.5468967895667, 1361.7140400516003, 1381.7996390523317, 1395.0374241773145, 1424.287856071964, 1426.7431862264891, 1432.4628264400849, 8146.159257314304, 8403.789905252417, 8410.537420441935, 8426.0, 8449.831003379897, 989.618996686281, 1205.7950148474781, 1231.1443546734934, 1232.2483285196024, 1254.6047994881583, 5676.574256930664, 6172.73458940916, 6300.936990630035, 6347.460465860361, 6362.2683391409655, 10184.32766707508, 10649.0, 10663.520141593492, 10674.35953842772, 10681.626143084955, 10132.797344053077, 10450.320729152581, 10520.526576303932, 10543.156547476183, 10561.155107591374, 8154.103048664642, 8304.958475207586, 8347.791305217419, 8351.707690230935, 8361.122082181406, 7120.928790712027, 8017.438779285394, 8076.757697269091, 8092.231238032377, 8140.715074972348, 9340.439573625605, 9361.251099911855, 9453.101955314234, 9473.715788526355, 9557.665481708107, 9267.0, 9581.0, 9750.902490974999, 9909.851352229867, 9932.453715045742, 5918.822435326947, 6763.086983257197, 6920.5466851961655, 7432.0, 7572.734954276574, 8127.187281271826, 8241.629126689193, 8245.134260902758, 8246.422750407413, 8254.463459875089, 7243.927560724325, 7339.559626422431, 7349.191588925107, 7493.325600695964, 7494.325510704063, 7221.097362829646, 7279.053723016186, 7342.816429589303, 7378.077740282465, 7393.0, 7727.92272077272, 7734.149243583314, 7788.454808163374, 7791.259830316111, 7794.337481314152, 6789.9336381943385, 7013.333733295329, 7068.5758854468895, 7081.4334853210585, 7087.255838137127, 7137.357637812622, 7138.607376594337, 7150.392216661642, 7157.606331651809, 7181.569105853666], "unit": "score", "name": "mootools.html", "value": 4979.268106522018}, {"lowerIsBetter": false, "replicates": [320.2713826045746, 324.88141828232705, 327.8393587142301, 330.50919384713706, 331.83242462556404, 6502.414782669559, 6561.311062338451, 6576.967115164418, 6577.0, 6578.473722102232, 6541.247756508003, 6545.574537655049, 6554.573952693084, 6555.573887697308, 6574.80275591733, 4621.491635920046, 4674.368960190365, 4710.152172608929, 4806.855794326176, 4875.731834749087, 3948.3287841066945, 4752.881177970561, 4812.614990800745, 4827.51724827518, 4878.4145902491755, 4320.984568626371, 4339.956600434002, 4768.189407800664, 4777.116233496814, 4784.617230621542, 4582.152301824157, 4596.0, 4687.484376718558, 4717.292406139107, 4719.197736384823, 4029.879103626896, 4040.272750904836, 4045.696572757055, 4048.4332193492933, 4057.7565346079036, 6105.267367915879, 6160.168377269101, 6207.937920620781, 6247.437730604267, 6307.590006649553, 5705.686187259699, 5709.800156994485, 5714.057180565202, 5715.628484148517, 5715.771369145229, 6876.965615171942, 6879.58722476653, 6884.070650462174, 6889.379955803952, 6892.207396149457, 4377.9781101094295, 4527.501974782771, 4555.726656400593, 4566.725996440225, 4570.360149579062, 7313.7074517019255, 7398.70405183792, 7431.256874312634, 7469.701211951569, 7481.289277518627, 4587.4036375271, 4613.953860461353, 4618.884527886763, 4622.722636641813, 4628.490866004737, 3859.0, 4677.883052923704, 4678.883027924329, 4683.765811709401, 4688.976555117202, 5601.467860553241, 5606.187102870061, 5607.7476513556985, 5614.101743720985, 5630.324361076697, 6740.595564266159, 6981.79054628362, 6993.300669933068, 7051.1891132519395, 7072.5402848814665, 1605.4782195786415, 1612.00861470195, 1621.100289339428, 1624.5288866228898, 1626.2031604513704, 985.2019863910256, 1007.0433088565892, 1024.88799652378, 1037.8287582548794, 1056.5984925728328, 6500.219973603103, 6638.634875081917, 6661.100751398596, 6661.4004739573675, 6696.06255124283, 5938.970305148533, 7365.7790266292095, 7369.668364923591, 7380.483366164317, 7398.593077380688, 6637.9668101660145, 6647.634380109141, 6652.501062420338, 6652.600843949379, 6654.201495820533, 3417.6069751978343, 3649.5073165122367, 3835.6547910688173, 3887.183691424776, 3895.9805200973815, 7407.481476296715, 7671.61641917913, 7677.808054798675, 7698.884516732253, 7704.5762483062845, 7396.630168491554, 7474.40204783616, 7476.887846682304, 7480.251974802476, 7483.513571617827, 7659.9234007660325, 7669.693212271558, 7672.347850432776, 7675.654595543326, 7675.961620191863, 6352.174217351715, 7016.5088443808445, 7313.488055836253, 7326.267373262631, 7327.230640782641, 5849.707514624337, 7168.17565979919, 7226.277372262732, 7229.060222171084, 7262.49162558637, 7244.311790379906, 7520.653309277319, 7656.885146722692, 7666.0, 7667.578283194478, 4775.641826863069, 4815.807367705253, 4815.927761083516, 4823.300621409806, 4827.541383568555, 6416.716656668592, 6587.505937054644, 6951.895721564079, 7202.3877970373105, 7218.5307954984855, 5971.745933354045, 6744.0, 6990.09128813251, 7003.964980175067, 7027.648617569203, 6924.826879328057, 6992.965035174791, 6994.300569942965, 6999.0, 7007.369336759716, 6831.658417079226, 6831.9658401707675, 6839.179298484214, 6843.589384636938, 6845.247022827385, 6755.392014718699, 6968.128983877015, 6991.7902462926195, 7000.544964577286, 7071.504994650325, 5322.627416080837, 5430.755615997368, 6742.089817874524, 6859.862802744017, 6873.072135261676], "unit": "score", "name": "prototype.html", "value": 5081.338144414833}, {"lowerIsBetter": false, "replicates": [674.5817593092282, 680.3502654964509, 722.7109156337465, 756.4440136499675, 774.8217909880727, 14711.779323310146, 14918.04671761984, 14929.253537323131, 14929.477468288613, 14997.10017398957, 13710.451581936735, 13828.446862125527, 13832.377543010563, 13853.0, 13863.4454621815, 707.6815433055133, 887.6582515731425, 914.5610107148573, 915.9496227707507, 916.7478943290615, 897.3897749530316, 907.9682211122612, 909.3134683314122, 909.6770646420524, 913.995430022849, 760.2321655128333, 963.2631037256505, 963.6868017894177, 964.1322809471461, 969.2730452160879, 952.6046690623417, 970.0251247496257, 974.3423189347162, 974.6198982396871, 974.8001659659824, 718.881384571545, 718.9029481020048, 720.1718024272129, 722.869883420984, 723.1936390924081, 14847.70304593902, 14932.104073755609, 14939.0, 14951.177685227309, 14955.102693838404, 12631.368431578476, 12668.4299206535, 12766.042546808934, 12856.35718214095, 13016.674583135497, 9907.306488545804, 10108.545115469822, 10233.539490722935, 10619.309744866636, 10881.619143329945, 3482.320947415255, 3524.7708898921487, 3573.535440392732, 3592.1738000259825, 3793.9241215175543, 83937.16062839415, 85385.57307213424, 85671.0, 87161.56419217927, 87583.0, 8155.959220203979, 9280.350375473652, 9362.485063321446, 9386.342955993152, 9744.464054477072, 21199.258025969324, 21208.681869771655, 21217.469563260733, 21221.89389053076, 21237.256696015564, 7266.418686505067, 7404.629768511661, 7524.247575242542, 7527.2849079337375, 7588.924110758932, 4067.0645751477327, 4124.092699606082, 4138.668906487474, 4191.371294305878, 4216.367544868233, 3558.3594952908206, 3602.0, 3605.02664280648, 3612.02475331659, 3614.6385361464168, 566.507138789256, 577.3216470646973, 654.5254690349459, 662.8144119646506, 669.855980964095, 493.5188191513246, 512.8615273876107, 558.8491107400979, 589.9085641725453, 602.8794241151787, 7687.6156192190165, 8023.1575684553445, 8114.148014458515, 8188.50868947865, 8471.449355791854, 551.6469459545893, 565.7652273519614, 583.9984426708215, 600.9513399118479, 603.405645439242, 3686.152184997412, 3735.7571757835653, 3791.7914514701956, 3980.721349505565, 4051.513818341818, 81659.59170204111, 84277.0, 84309.57845210735, 84439.15560844314, 84694.0, 70945.6452717733, 72346.0, 72394.63802680952, 72834.9074763879, 73472.0, 64172.0, 64634.67682661651, 65306.673466633314, 69372.65313673399, 70174.59650806955, 8428.157184281523, 8491.787705307417, 8579.2707619853, 8582.173411443737, 8599.871001935102, 47365.7631711853, 48475.78810529624, 48784.0, 49535.504644953086, 49797.0, 66447.66776166088, 68961.3103868955, 70583.0, 71250.0, 71760.69435833658, 10599.0, 10701.143908487147, 10787.838182427427, 10992.670219893416, 11584.073274138218, 10477.476126193813, 11954.402279886144, 12348.197367171106, 12368.876311236772, 12412.827755142633, 11296.887031129912, 11373.31760094397, 11517.308961462339, 11764.882351176379, 12025.4588543514, 11286.548538058385, 11399.373034483184, 11563.421828908353, 12046.518139274682, 12421.565245216736, 11072.612458563912, 11297.491612877604, 11415.315081095161, 11427.015520186078, 11510.194286399872, 9580.520973951136, 9707.417554946725, 9723.47858245919, 9926.205903527556, 10041.297109202285, 7649.349805266615, 8912.598933048159, 9482.857757133786, 9545.809083818145, 9613.230941524802], "unit": "score", "name": "yui.html", "value": 6866.808829068139}], "name": "dromaeo_css", "value": 7478.8129791977035}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 111.0, 111.0, 112.0, 113.0, 116.0, 111.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.8}, {"lowerIsBetter": true, "replicates": [155.0, 120.0, 146.0, 157.0, 139.0, 167.0, 165.0, 144.0, 157.0, 115.0], "unit": "score", "name": "audio-beat-detection", "value": 146.5}, {"lowerIsBetter": true, "replicates": [182.0, 183.0, 186.0, 185.0, 184.0, 186.0, 185.0, 186.0, 181.0, 185.0], "unit": "score", "name": "audio-dft", "value": 184.3}, {"lowerIsBetter": true, "replicates": [71.0, 75.0, 85.0, 79.0, 56.0, 57.0, 58.0, 78.0, 75.0, 55.0], "unit": "score", "name": "audio-fft", "value": 68.9}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 129.0, 81.0, 78.0, 83.0, 117.0, 78.0, 80.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 95.1}, {"lowerIsBetter": true, "replicates": [89.0, 86.0, 91.0, 87.0, 90.0, 86.0, 91.0, 87.0, 89.0, 87.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 88.3}, {"lowerIsBetter": true, "replicates": [125.0, 93.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 97.4}, {"lowerIsBetter": true, "replicates": [83.0, 87.0, 84.0, 107.0, 136.0, 74.0, 144.0, 143.0, 131.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 107.4}, {"lowerIsBetter": true, "replicates": [44.0, 52.0, 54.0, 49.0, 51.0, 54.0, 54.0, 64.0, 55.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 53.0}, {"lowerIsBetter": true, "replicates": [62.0, 72.0, 67.0, 74.0, 71.0, 75.0, 91.0, 96.0, 99.0, 99.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 80.6}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 83.0, 81.0, 83.0, 82.0, 82.0, 81.0, 84.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.5}, {"lowerIsBetter": true, "replicates": [123.0, 132.0, 124.0, 131.0, 119.0, 120.0, 124.0, 124.0, 118.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.6}, {"lowerIsBetter": true, "replicates": [138.0, 126.0, 138.0, 138.0, 137.0, 126.0, 136.0, 136.0, 136.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 135.0}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 67.0, 68.0, 68.0, 73.0, 92.0, 92.0, 92.0, 74.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 75.9}], "name": "kraken", "value": 1450.3}]} 10:07:53 INFO - Return code: 0 10:07:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:07:54 ERROR - # TBPL SUCCESS # 10:07:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:07:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:07:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:07:54 INFO - 2016-03-10 10:07:54.039 screenresolution[1679:9670] starting screenresolution argv=screenresolution get 10:07:54 INFO - 2016-03-10 10:07:54.041 screenresolution[1679:9670] Display 0: 1600x1200x32@60 10:07:54 INFO - 2016-03-10 10:07:54.047 screenresolution[1680:9673] starting screenresolution argv=screenresolution list 10:07:54 INFO - Available Modes on Display 0 10:07:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:07:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:07:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:07:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:07:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:07:54 INFO - Intel Iris: 10:07:54 INFO - Chipset Model: Intel Iris 10:07:54 INFO - Type: GPU 10:07:54 INFO - Bus: Built-In 10:07:54 INFO - VRAM (Dynamic, Max): 1536 MB 10:07:54 INFO - Vendor: Intel (0x8086) 10:07:54 INFO - Device ID: 0x0a2e 10:07:54 INFO - Revision ID: 0x0009 10:07:54 INFO - Displays: 10:07:54 INFO - Display: 10:07:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:07:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:07:54 INFO - Display Serial Number: 1600x1200 60 10:07:54 INFO - Main Display: Yes 10:07:54 INFO - Mirror: Off 10:07:54 INFO - Online: Yes 10:07:54 INFO - Rotation: Supported 10:07:54 INFO - Return code: 0 10:07:54 INFO - Running post-action listener: _resource_record_post_action 10:07:54 INFO - Running post-run listener: _resource_record_post_run 10:07:55 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 39589376; Write bytes: 704761344; Read time: 428; Write time: 2172 10:07:55 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392315392; Write bytes: 382131712; Read time: 30227; Write time: 703 10:07:55 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 33308160; Write bytes: 299900928; Read time: 344; Write time: 1418 10:07:55 INFO - Running post-run listener: _upload_blobber_files 10:07:55 INFO - Blob upload gear active. 10:07:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:07:55 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 10:07:55 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'] 10:07:55 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 10:07:55 INFO - (blobuploader) - INFO - Open directory for files ... 10:07:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:07:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:07:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:56 INFO - (blobuploader) - INFO - Done attempting. 10:07:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:07:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:07:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:57 INFO - (blobuploader) - INFO - Done attempting. 10:07:57 INFO - (blobuploader) - INFO - Iteration through files over. 10:07:57 INFO - Return code: 0 10:07:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:07:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:07:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e35caf9372530867b9e4ff09ee90efbb29a7f84a1e17a9f355db273ba2276b58c99d55ba828b6c39996e73931ab9b1fa2c7c82c4cf3d91ed8cdc6343cfff755c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5608e1f053998ce172737f98c8e5eee9a62dba3d5b68ade74eaf9b873e87e0b1d70f4cbeecd2751947443f568b948fa69a907034c6f2dffed9377e103c858647"} 10:07:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:07:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:07:57 INFO - Contents: 10:07:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e35caf9372530867b9e4ff09ee90efbb29a7f84a1e17a9f355db273ba2276b58c99d55ba828b6c39996e73931ab9b1fa2c7c82c4cf3d91ed8cdc6343cfff755c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5608e1f053998ce172737f98c8e5eee9a62dba3d5b68ade74eaf9b873e87e0b1d70f4cbeecd2751947443f568b948fa69a907034c6f2dffed9377e103c858647"} 10:07:57 INFO - Running post-run listener: copy_logs_to_upload_dir 10:07:57 INFO - Copying logs to upload dir... 10:07:57 INFO - mkdir: /builds/slave/test/build/upload/logs 10:07:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.491246 ========= master_lag: 9.70 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-03-10 10:08:07.625280) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-10 10:08:07.629083) ========= 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.UShJEVb8P8/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.5TkN4JTUJG/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e35caf9372530867b9e4ff09ee90efbb29a7f84a1e17a9f355db273ba2276b58c99d55ba828b6c39996e73931ab9b1fa2c7c82c4cf3d91ed8cdc6343cfff755c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5608e1f053998ce172737f98c8e5eee9a62dba3d5b68ade74eaf9b873e87e0b1d70f4cbeecd2751947443f568b948fa69a907034c6f2dffed9377e103c858647"} build_url:https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/PfdsgLGAS_6hMfdFqoRysA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e35caf9372530867b9e4ff09ee90efbb29a7f84a1e17a9f355db273ba2276b58c99d55ba828b6c39996e73931ab9b1fa2c7c82c4cf3d91ed8cdc6343cfff755c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5608e1f053998ce172737f98c8e5eee9a62dba3d5b68ade74eaf9b873e87e0b1d70f4cbeecd2751947443f568b948fa69a907034c6f2dffed9377e103c858647"}' ========= master_lag: 0.45 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-10 10:08:08.087222) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-03-10 10:08:08.087613) ========= 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.UShJEVb8P8/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.5TkN4JTUJG/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005196 ========= master_lag: 7.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-03-10 10:08:15.192071) ========= ========= Started reboot skipped (results: 3, elapsed: 4 secs) (at 2016-03-10 10:08:15.192348) ========= ========= Finished reboot skipped (results: 3, elapsed: 4 secs) (at 2016-03-10 10:08:19.506818) ========= ========= Total master_lag: 25.15 =========