builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0106 starttime: 1456987727.78 results: success (0) buildid: 20160302204221 builduid: 2a6ea574cccf4561aad312a57dfaccce revision: 2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.779142) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.779639) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.780032) ========= 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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.006008 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.805559) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.805923) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.819562) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.819916) ========= 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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.004962 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.867797) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.871171) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.871588) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:47.871918) ========= 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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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-02 22:48:47-- 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% 161M=0s 2016-03-02 22:48:48 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159342 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:48.046685) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:48.047057) ========= 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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.021169 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:48.096692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:48.097107) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3 --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 2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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-02 22:48:48,177 truncating revision to first 12 chars 2016-03-02 22:48:48,177 Setting DEBUG logging. 2016-03-02 22:48:48,177 attempt 1/10 2016-03-02 22:48:48,177 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2eb7b3fd4cc0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-02 22:48:48,662 unpacking tar archive at: fx-team-2eb7b3fd4cc0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.735338 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:48.851525) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:48.851952) ========= echo 2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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 2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3 program finished with exit code 0 elapsedTime=0.005186 script_repo_revision: '2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:48.902988) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:48.903444) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-02 22:48:48.917330) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-03-02 22:48:48.917654) ========= /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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:48:49 INFO - MultiFileLogger online at 20160302 22:48:49 in /builds/slave/test 22:48:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:48:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:48:49 INFO - {'append_to_log': False, 22:48:49 INFO - 'base_work_dir': '/builds/slave/test', 22:48:49 INFO - 'blob_upload_branch': 'Fx-Team', 22:48:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:48:49 INFO - 'branch': 'Fx-Team', 22:48:49 INFO - 'buildbot_json_path': 'buildprops.json', 22:48:49 INFO - 'config_files': ('talos/mac_config.py',), 22:48:49 INFO - 'default_actions': ('clobber', 22:48:49 INFO - 'read-buildbot-config', 22:48:49 INFO - 'download-and-extract', 22:48:49 INFO - 'populate-webroot', 22:48:49 INFO - 'create-virtualenv', 22:48:49 INFO - 'install', 22:48:49 INFO - 'run-tests'), 22:48:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:48:49 INFO - 'download_minidump_stackwalk': True, 22:48:49 INFO - 'download_symbols': 'ondemand', 22:48:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:48:49 INFO - 'tooltool.py': '/tools/tooltool.py', 22:48:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:48:49 INFO - '/tools/misc-python/virtualenv.py')}, 22:48:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:48:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:48:49 INFO - 'installer_path': 'installer.exe', 22:48:49 INFO - 'log_level': 'info', 22:48:49 INFO - 'log_name': 'talos', 22:48:49 INFO - 'log_to_console': True, 22:48:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:48:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:48:49 INFO - 'opt_config_files': (), 22:48:49 INFO - 'pip_index': False, 22:48:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:48:49 INFO - 'cmd': ('bash', 22:48:49 INFO - '-c', 22:48:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:48:49 INFO - 'enabled': True, 22:48:49 INFO - 'halt_on_failure': False, 22:48:49 INFO - 'name': 'check_screen_resolution'},), 22:48:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:48:49 INFO - 'cmd': ('bash', 22:48:49 INFO - '-c', 22:48:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:48:49 INFO - 'enabled': True, 22:48:49 INFO - 'halt_on_failure': False, 22:48:49 INFO - 'name': 'check_screen_resolution'},), 22:48:49 INFO - 'run_cmd_checks_enabled': True, 22:48:49 INFO - 'sps_profile': False, 22:48:49 INFO - 'sps_profile_interval': 0, 22:48:49 INFO - 'suite': 'dromaeojs', 22:48:49 INFO - 'system_bits': '32', 22:48:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:48:49 INFO - 'title': 't-yosemite-r7-0106', 22:48:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:48:49 INFO - 'use_talos_json': True, 22:48:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:48:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:48:49 INFO - 'work_dir': 'build'} 22:48:49 INFO - ##### 22:48:49 INFO - ##### Running clobber step. 22:48:49 INFO - ##### 22:48:49 INFO - Running pre-action listener: _resource_record_pre_action 22:48:49 INFO - Running main action method: clobber 22:48:49 INFO - rmtree: /builds/slave/test/build 22:48:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:48:50 INFO - Running post-action listener: _resource_record_post_action 22:48:50 INFO - ##### 22:48:50 INFO - ##### Running read-buildbot-config step. 22:48:50 INFO - ##### 22:48:50 INFO - Running pre-action listener: _resource_record_pre_action 22:48:50 INFO - Running main action method: read_buildbot_config 22:48:50 INFO - Using buildbot properties: 22:48:50 INFO - { 22:48:50 INFO - "project": "", 22:48:50 INFO - "product": "firefox", 22:48:50 INFO - "script_repo_revision": "production", 22:48:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:48:50 INFO - "repository": "", 22:48:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:48:50 INFO - "buildid": "20160302204221", 22:48:50 INFO - "pgo_build": "False", 22:48:50 INFO - "basedir": "/builds/slave/test", 22:48:50 INFO - "buildnumber": 442, 22:48:50 INFO - "slavename": "t-yosemite-r7-0106", 22:48:50 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:48:50 INFO - "platform": "yosemite_r7", 22:48:50 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:48:50 INFO - "branch": "fx-team", 22:48:50 INFO - "repo_path": "integration/fx-team", 22:48:50 INFO - "revision": "2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3", 22:48:50 INFO - "stage_platform": "macosx64", 22:48:50 INFO - "builduid": "2a6ea574cccf4561aad312a57dfaccce", 22:48:50 INFO - "slavebuilddir": "test" 22:48:50 INFO - } 22:48:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 22:48:50 INFO - Running post-action listener: _resource_record_post_action 22:48:50 INFO - ##### 22:48:50 INFO - ##### Running download-and-extract step. 22:48:50 INFO - ##### 22:48:50 INFO - Running pre-action listener: _resource_record_pre_action 22:48:50 INFO - Running main action method: download_and_extract 22:48:50 INFO - mkdir: /builds/slave/test/build/tests 22:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:50 INFO - https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:48:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json 22:48:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json 22:48:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a1.en-US.mac.test_packages.json 22:48:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.test_packages.json'}, attempt #1 22:48:51 INFO - Downloaded 1277 bytes. 22:48:51 INFO - Reading from file /builds/slave/test/build/firefox-47.0a1.en-US.mac.test_packages.json 22:48:51 INFO - Using the following test package requirements: 22:48:51 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 22:48:51 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:48:51 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 22:48:51 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:48:51 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 22:48:51 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:48:51 INFO - u'jsshell-mac.zip'], 22:48:51 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:48:51 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 22:48:51 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 22:48:51 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:48:51 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 22:48:51 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:48:51 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 22:48:51 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:48:51 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 22:48:51 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 22:48:51 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 22:48:51 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 22:48:51 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:51 INFO - https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:48:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:48:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:48:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:48:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:48:52 INFO - Downloaded 17855065 bytes. 22:48:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:48:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:48:55 INFO - Return code: 0 22:48:55 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:55 INFO - https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:48:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:48:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:48:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 22:48:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:48:57 INFO - Downloaded 17855065 bytes. 22:48:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:48:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:49:00 INFO - Return code: 0 22:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:00 INFO - https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:49:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 22:49:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 22:49:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 22:49:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:49:01 INFO - Downloaded 10808263 bytes. 22:49:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:49:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:49:02 INFO - Return code: 0 22:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:02 INFO - https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:49:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 22:49:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 22:49:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 22:49:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 22:49:05 INFO - Downloaded 110962870 bytes. 22:49:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 22:49:05 INFO - mkdir: /builds/slave/test/properties 22:49:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:49:05 INFO - Writing to file /builds/slave/test/properties/build_url 22:49:05 INFO - Contents: 22:49:05 INFO - build_url:https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 22:49:06 INFO - Running post-action listener: _resource_record_post_action 22:49:06 INFO - Running post-action listener: set_extra_try_arguments 22:49:06 INFO - ##### 22:49:06 INFO - ##### Running populate-webroot step. 22:49:06 INFO - ##### 22:49:06 INFO - Running pre-action listener: _resource_record_pre_action 22:49:06 INFO - Running main action method: populate_webroot 22:49:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:49:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:49:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:49:06 INFO - '--tppagecycles', 22:49:06 INFO - '7'], 22:49:06 INFO - 'tests': ['tsvgm']}}, 22:49:06 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:49:06 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:49:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:49:06 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 22:49:07 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:49:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:49:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:49:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:07 INFO - 'talos_options': ['--e10s'], 22:49:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:49:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:07 INFO - 'tests': ['damp', 'tps']}, 22:49:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:07 INFO - 'talos_options': ['--e10s'], 22:49:07 INFO - 'tests': ['damp', 'tps']}, 22:49:07 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:49:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:49:07 INFO - 'other': {'tests': ['a11yr', 22:49:07 INFO - 'ts_paint', 22:49:07 INFO - 'tpaint', 22:49:07 INFO - 'sessionrestore', 22:49:07 INFO - 'sessionrestore_no_auto_restore']}, 22:49:07 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:49:07 INFO - 'tests': ['a11yr', 22:49:07 INFO - 'ts_paint', 22:49:07 INFO - 'tpaint', 22:49:07 INFO - 'sessionrestore', 22:49:07 INFO - 'sessionrestore_no_auto_restore']}, 22:49:07 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:49:07 INFO - 'tests': ['a11yr', 22:49:07 INFO - 'ts_paint', 22:49:07 INFO - 'tpaint', 22:49:07 INFO - 'sessionrestore', 22:49:07 INFO - 'sessionrestore_no_auto_restore']}, 22:49:07 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:49:07 INFO - 'tests': ['a11yr', 22:49:07 INFO - 'ts_paint', 22:49:07 INFO - 'tpaint', 22:49:07 INFO - 'sessionrestore', 22:49:07 INFO - 'sessionrestore_no_auto_restore']}, 22:49:07 INFO - 'other_l64': {'tests': ['a11yr', 22:49:07 INFO - 'ts_paint', 22:49:07 INFO - 'tpaint', 22:49:07 INFO - 'sessionrestore', 22:49:07 INFO - 'sessionrestore_no_auto_restore']}, 22:49:07 INFO - 'other_nol64': {'tests': ['a11yr', 22:49:07 INFO - 'ts_paint', 22:49:07 INFO - 'tpaint', 22:49:07 INFO - 'sessionrestore', 22:49:07 INFO - 'sessionrestore_no_auto_restore']}, 22:49:07 INFO - 'svgr': {'tests': ['tsvgx', 22:49:07 INFO - 'tsvgr_opacity', 22:49:07 INFO - 'tart', 22:49:07 INFO - 'tscrollx', 22:49:07 INFO - 'cart']}, 22:49:07 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:49:07 INFO - 'tests': ['tsvgx', 22:49:07 INFO - 'tsvgr_opacity', 22:49:07 INFO - 'tart', 22:49:07 INFO - 'tscrollx', 22:49:07 INFO - 'cart']}, 22:49:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:07 INFO - 'tests': ['tp5o']}, 22:49:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:07 INFO - 'talos_options': ['--e10s'], 22:49:07 INFO - 'tests': ['tp5o']}, 22:49:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:07 INFO - 'talos_options': ['--xperf_path', 22:49:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:49:07 INFO - 'tests': ['tp5n']}, 22:49:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:07 INFO - 'talos_options': ['--e10s', 22:49:07 INFO - '--xperf_path', 22:49:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:49:07 INFO - 'tests': ['tp5n']}}, 22:49:07 INFO - 'talos.zip': {'path': '', 22:49:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:49:07 INFO - Running post-action listener: _resource_record_post_action 22:49:07 INFO - ##### 22:49:07 INFO - ##### Running create-virtualenv step. 22:49:07 INFO - ##### 22:49:07 INFO - Running pre-action listener: _resource_record_pre_action 22:49:07 INFO - Running main action method: create_virtualenv 22:49:07 INFO - Creating virtualenv /builds/slave/test/build/venv 22:49:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:49:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:49:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:49:07 INFO - Using real prefix '/tools/python27' 22:49:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:49:07 INFO - Installing distribute.............................................................................................................................................................................................done. 22:49:10 INFO - Installing pip.................done. 22:49:10 INFO - Return code: 0 22:49:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1078fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff249c4a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, '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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:49:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:49:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:49:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:10 INFO - 'HOME': '/Users/cltbld', 22:49:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:10 INFO - 'LOGNAME': 'cltbld', 22:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:10 INFO - 'MOZ_NO_REMOTE': '1', 22:49:10 INFO - 'NO_EM_RESTART': '1', 22:49:10 INFO - 'PAGER': '/bin/cat', 22:49:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:10 INFO - 'PWD': '/builds/slave/test', 22:49:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:10 INFO - 'SHELL': '/bin/bash', 22:49:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:49:10 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:49:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:10 INFO - 'USER': 'cltbld', 22:49:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:10 INFO - 'XPC_FLAGS': '0x0', 22:49:10 INFO - 'XPC_SERVICE_NAME': '0', 22:49:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:49:10 INFO - Cleaning up... 22:49:10 INFO - Return code: 0 22:49:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1078fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff249c4a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, '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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:49:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:49:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:49:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:10 INFO - 'HOME': '/Users/cltbld', 22:49:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:10 INFO - 'LOGNAME': 'cltbld', 22:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:10 INFO - 'MOZ_NO_REMOTE': '1', 22:49:10 INFO - 'NO_EM_RESTART': '1', 22:49:10 INFO - 'PAGER': '/bin/cat', 22:49:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:10 INFO - 'PWD': '/builds/slave/test', 22:49:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:10 INFO - 'SHELL': '/bin/bash', 22:49:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:49:10 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:49:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:10 INFO - 'USER': 'cltbld', 22:49:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:10 INFO - 'XPC_FLAGS': '0x0', 22:49:10 INFO - 'XPC_SERVICE_NAME': '0', 22:49:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:11 INFO - Downloading/unpacking psutil>=0.7.1 22:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:49:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:49:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:49:13 INFO - Installing collected packages: psutil 22:49:13 INFO - Running setup.py install for psutil 22:49:13 INFO - building 'psutil._psutil_osx' extension 22:49:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:49:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:49:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:49:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:49:14 INFO - building 'psutil._psutil_posix' extension 22:49:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:49:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:49:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:49:14 INFO - ^ 22:49:14 INFO - 1 warning generated. 22:49:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:49:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:49:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:49:14 INFO - Successfully installed psutil 22:49:14 INFO - Cleaning up... 22:49:14 INFO - Return code: 0 22:49:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:14 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 0x1078fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff249c4a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, '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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:14 INFO - 'HOME': '/Users/cltbld', 22:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:14 INFO - 'LOGNAME': 'cltbld', 22:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:14 INFO - 'MOZ_NO_REMOTE': '1', 22:49:14 INFO - 'NO_EM_RESTART': '1', 22:49:14 INFO - 'PAGER': '/bin/cat', 22:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:14 INFO - 'PWD': '/builds/slave/test', 22:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:14 INFO - 'SHELL': '/bin/bash', 22:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:49:14 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:14 INFO - 'USER': 'cltbld', 22:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:14 INFO - 'XPC_FLAGS': '0x0', 22:49:14 INFO - 'XPC_SERVICE_NAME': '0', 22:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:49:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:49:17 INFO - Installing collected packages: mozsystemmonitor 22:49:17 INFO - Running setup.py install for mozsystemmonitor 22:49:17 INFO - Successfully installed mozsystemmonitor 22:49:17 INFO - Cleaning up... 22:49:17 INFO - Return code: 0 22:49:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1078fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff249c4a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, '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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:49:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:49:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:49:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:17 INFO - 'HOME': '/Users/cltbld', 22:49:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:17 INFO - 'LOGNAME': 'cltbld', 22:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:17 INFO - 'MOZ_NO_REMOTE': '1', 22:49:17 INFO - 'NO_EM_RESTART': '1', 22:49:17 INFO - 'PAGER': '/bin/cat', 22:49:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:17 INFO - 'PWD': '/builds/slave/test', 22:49:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:17 INFO - 'SHELL': '/bin/bash', 22:49:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:49:17 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:49:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:17 INFO - 'USER': 'cltbld', 22:49:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:17 INFO - 'XPC_FLAGS': '0x0', 22:49:17 INFO - 'XPC_SERVICE_NAME': '0', 22:49:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:17 INFO - Downloading/unpacking blobuploader==1.2.4 22:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:20 INFO - Downloading blobuploader-1.2.4.tar.gz 22:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:49:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:49:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49:21 INFO - Downloading docopt-0.6.1.tar.gz 22:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:49:21 INFO - Installing collected packages: blobuploader, requests, docopt 22:49:21 INFO - Running setup.py install for blobuploader 22:49:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:49:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:49:21 INFO - Running setup.py install for requests 22:49:21 INFO - Running setup.py install for docopt 22:49:22 INFO - Successfully installed blobuploader requests docopt 22:49:22 INFO - Cleaning up... 22:49:22 INFO - Return code: 0 22:49:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:22 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 0x1078fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff249c4a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, '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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:22 INFO - 'HOME': '/Users/cltbld', 22:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:22 INFO - 'LOGNAME': 'cltbld', 22:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:22 INFO - 'MOZ_NO_REMOTE': '1', 22:49:22 INFO - 'NO_EM_RESTART': '1', 22:49:22 INFO - 'PAGER': '/bin/cat', 22:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:22 INFO - 'PWD': '/builds/slave/test', 22:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:22 INFO - 'SHELL': '/bin/bash', 22:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:49:22 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:22 INFO - 'USER': 'cltbld', 22:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:22 INFO - 'XPC_FLAGS': '0x0', 22:49:22 INFO - 'XPC_SERVICE_NAME': '0', 22:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:49:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-M79llI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:49:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-eF8u7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:49:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-5WGJcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:49:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-eECcEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:49:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-AOxoRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:49:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:49:22 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-BnbeDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:49:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-hw9RDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:49:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-4SjD8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:49:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-_COwpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:49:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-q_qm2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:49:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-fS8yYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:49:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-1P9i7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:49:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-4__7AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:49:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-ieQ41_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:23 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-IXirx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:49:24 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-SoXzn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:49:24 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-FDKLCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:49:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:49:24 INFO - Running setup.py install for manifestparser 22:49:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Running setup.py install for mozcrash 22:49:24 INFO - Running setup.py install for mozdebug 22:49:24 INFO - Running setup.py install for mozdevice 22:49:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:49:24 INFO - Running setup.py install for mozfile 22:49:24 INFO - Running setup.py install for mozhttpd 22:49:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Running setup.py install for mozinfo 22:49:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Running setup.py install for mozInstall 22:49:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Running setup.py install for mozleak 22:49:25 INFO - Running setup.py install for mozlog 22:49:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Running setup.py install for moznetwork 22:49:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:49:25 INFO - Running setup.py install for mozprocess 22:49:26 INFO - Running setup.py install for mozprofile 22:49:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:49:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:49:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:49:26 INFO - Running setup.py install for mozrunner 22:49:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:49:26 INFO - Running setup.py install for mozscreenshot 22:49:26 INFO - Running setup.py install for moztest 22:49:26 INFO - Running setup.py install for mozversion 22:49:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:49:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:49:26 INFO - Cleaning up... 22:49:26 INFO - Return code: 0 22:49:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:26 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 0x1078fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff249c4a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, '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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:49:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:49:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:49:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:26 INFO - 'HOME': '/Users/cltbld', 22:49:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:26 INFO - 'LOGNAME': 'cltbld', 22:49:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:26 INFO - 'MOZ_NO_REMOTE': '1', 22:49:26 INFO - 'NO_EM_RESTART': '1', 22:49:26 INFO - 'PAGER': '/bin/cat', 22:49:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:26 INFO - 'PWD': '/builds/slave/test', 22:49:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:26 INFO - 'SHELL': '/bin/bash', 22:49:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:49:26 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:49:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:26 INFO - 'USER': 'cltbld', 22:49:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:26 INFO - 'XPC_FLAGS': '0x0', 22:49:26 INFO - 'XPC_SERVICE_NAME': '0', 22:49:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:49:27 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-W2iME8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:49:27 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-vDeRSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:49:27 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-K3t1td-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:49:27 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-CbFBoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:49:27 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-01B9rE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:49:27 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-c4GaJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:49:27 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-BRa7zG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:49:27 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-ctZ_gT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:49:27 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-XvT17k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:49:28 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-K6Kmj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:49:28 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-gzDhRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:49:28 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-MzZJTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:49:28 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-BC0jZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:49:28 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Cf4pqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:28 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-Ei3MSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:49:28 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-GxLhR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:49:28 INFO - Running setup.py (path:/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/pip-MEBjZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:49:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:31 INFO - Downloading blessings-1.6.tar.gz 22:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:49:31 INFO - Installing collected packages: blessings 22:49:31 INFO - Running setup.py install for blessings 22:49:31 INFO - Successfully installed blessings 22:49:31 INFO - Cleaning up... 22:49:31 INFO - Return code: 0 22:49:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:49:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:49:31 INFO - Reading from file tmpfile_stdout 22:49:31 INFO - Current package versions: 22:49:31 INFO - blessings == 1.6 22:49:31 INFO - blobuploader == 1.2.4 22:49:31 INFO - docopt == 0.6.1 22:49:31 INFO - manifestparser == 1.1 22:49:31 INFO - mozInstall == 1.12 22:49:31 INFO - mozcrash == 0.16 22:49:31 INFO - mozdebug == 0.1 22:49:31 INFO - mozdevice == 0.48 22:49:31 INFO - mozfile == 1.2 22:49:31 INFO - mozhttpd == 0.7 22:49:31 INFO - mozinfo == 0.9 22:49:31 INFO - mozleak == 0.1 22:49:31 INFO - mozlog == 3.1 22:49:31 INFO - moznetwork == 0.27 22:49:31 INFO - mozprocess == 0.22 22:49:31 INFO - mozprofile == 0.28 22:49:31 INFO - mozrunner == 6.11 22:49:31 INFO - mozscreenshot == 0.1 22:49:31 INFO - mozsystemmonitor == 0.0 22:49:31 INFO - moztest == 0.7 22:49:31 INFO - mozversion == 1.4 22:49:31 INFO - psutil == 3.1.1 22:49:31 INFO - requests == 1.2.3 22:49:31 INFO - wsgiref == 0.1.2 22:49:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:31 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 0x1078fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff249c4a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, '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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:49:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:49:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:49:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:31 INFO - 'HOME': '/Users/cltbld', 22:49:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:31 INFO - 'LOGNAME': 'cltbld', 22:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:31 INFO - 'MOZ_NO_REMOTE': '1', 22:49:31 INFO - 'NO_EM_RESTART': '1', 22:49:31 INFO - 'PAGER': '/bin/cat', 22:49:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:31 INFO - 'PWD': '/builds/slave/test', 22:49:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:31 INFO - 'SHELL': '/bin/bash', 22:49:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:49:31 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:49:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:31 INFO - 'USER': 'cltbld', 22:49:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:31 INFO - 'XPC_FLAGS': '0x0', 22:49:31 INFO - 'XPC_SERVICE_NAME': '0', 22:49:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:49:32 INFO - Cleaning up... 22:49:32 INFO - Return code: 0 22:49:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:32 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 0x1078fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff249c4a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, '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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:32 INFO - 'HOME': '/Users/cltbld', 22:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:32 INFO - 'LOGNAME': 'cltbld', 22:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:32 INFO - 'MOZ_NO_REMOTE': '1', 22:49:32 INFO - 'NO_EM_RESTART': '1', 22:49:32 INFO - 'PAGER': '/bin/cat', 22:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:32 INFO - 'PWD': '/builds/slave/test', 22:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:32 INFO - 'SHELL': '/bin/bash', 22:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:49:32 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:32 INFO - 'USER': 'cltbld', 22:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:32 INFO - 'XPC_FLAGS': '0x0', 22:49:32 INFO - 'XPC_SERVICE_NAME': '0', 22:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:32 INFO - Downloading/unpacking jsonschema 22:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:49:34 INFO - Downloading/unpacking functools32 (from jsonschema) 22:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:35 INFO - Downloading functools32-3.2.3-2.tar.gz 22:49:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:49:35 INFO - warning: no files found matching '*.txt' 22:49:35 INFO - no previously-included directories found matching 'build' 22:49:35 INFO - no previously-included directories found matching 'dist' 22:49:35 INFO - no previously-included directories found matching '.git*' 22:49:35 INFO - Installing collected packages: jsonschema, functools32 22:49:35 INFO - Running setup.py install for functools32 22:49:35 INFO - warning: no files found matching '*.txt' 22:49:35 INFO - no previously-included directories found matching 'build' 22:49:35 INFO - no previously-included directories found matching 'dist' 22:49:35 INFO - no previously-included directories found matching '.git*' 22:49:35 INFO - Successfully installed jsonschema functools32 22:49:35 INFO - Cleaning up... 22:49:35 INFO - Return code: 0 22:49:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:35 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 0x1078fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff249c4a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, '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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:49:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:35 INFO - 'HOME': '/Users/cltbld', 22:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:35 INFO - 'LOGNAME': 'cltbld', 22:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:35 INFO - 'MOZ_NO_REMOTE': '1', 22:49:35 INFO - 'NO_EM_RESTART': '1', 22:49:35 INFO - 'PAGER': '/bin/cat', 22:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:35 INFO - 'PWD': '/builds/slave/test', 22:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:35 INFO - 'SHELL': '/bin/bash', 22:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:49:35 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:35 INFO - 'USER': 'cltbld', 22:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:35 INFO - 'XPC_FLAGS': '0x0', 22:49:35 INFO - 'XPC_SERVICE_NAME': '0', 22:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:35 INFO - Downloading/unpacking flake8 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49:38 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:49:38 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49:38 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:49:38 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49: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 22:49:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:49:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:49:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:49:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:49:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:49:39 INFO - no previously-included directories found matching 'docs/_build' 22:49:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:49:39 INFO - Running setup.py install for pep8 22:49:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:49:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:49:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:49:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:49:39 INFO - no previously-included directories found matching 'docs/_build' 22:49:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:49:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:49:39 INFO - Cleaning up... 22:49:39 INFO - Return code: 0 22:49:39 INFO - Running post-action listener: _resource_record_post_action 22:49:39 INFO - Running post-action listener: _start_resource_monitoring 22:49:39 INFO - Starting resource monitoring. 22:49:39 INFO - ##### 22:49:39 INFO - ##### Running install step. 22:49:39 INFO - ##### 22:49:39 INFO - Running pre-action listener: _resource_record_pre_action 22:49:39 INFO - Running main action method: install 22:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:49:40 INFO - Reading from file tmpfile_stdout 22:49:40 INFO - Detecting whether we're running mozinstall >=1.0... 22:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:49:40 INFO - Reading from file tmpfile_stdout 22:49:40 INFO - Output received: 22:49:40 INFO - Usage: mozinstall [options] installer 22:49:40 INFO - Options: 22:49:40 INFO - -h, --help show this help message and exit 22:49:40 INFO - -d DEST, --destination=DEST 22:49:40 INFO - Directory to install application into. [default: 22:49:40 INFO - "/builds/slave/test"] 22:49:40 INFO - --app=APP Application being installed. [default: firefox] 22:49:40 INFO - mkdir: /builds/slave/test/build/application 22:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:50:14 INFO - Reading from file tmpfile_stdout 22:50:14 INFO - Output received: 22:50:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:50:14 INFO - Running post-action listener: _resource_record_post_action 22:50:14 INFO - ##### 22:50:14 INFO - ##### Running run-tests step. 22:50:14 INFO - ##### 22:50:14 INFO - Running pre-action listener: _resource_record_pre_action 22:50:14 INFO - Running main action method: run_tests 22:50:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:50:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:50:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:50:14 INFO - 2016-03-02 22:50:14.308 screenresolution[3597:90121] starting screenresolution argv=screenresolution get 22:50:14 INFO - 2016-03-02 22:50:14.310 screenresolution[3597:90121] Display 0: 1600x1200x32@60 22:50:14 INFO - 2016-03-02 22:50:14.316 screenresolution[3598:90123] starting screenresolution argv=screenresolution list 22:50:14 INFO - Available Modes on Display 0 22:50:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:50:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:50:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:50:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:50:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:50:14 INFO - Intel Iris: 22:50:14 INFO - Chipset Model: Intel Iris 22:50:14 INFO - Type: GPU 22:50:14 INFO - Bus: Built-In 22:50:14 INFO - VRAM (Dynamic, Max): 1536 MB 22:50:14 INFO - Vendor: Intel (0x8086) 22:50:14 INFO - Device ID: 0x0a2e 22:50:14 INFO - Revision ID: 0x0009 22:50:14 INFO - Displays: 22:50:14 INFO - Display: 22:50:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:50:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:50:14 INFO - Display Serial Number: 1600x1200 60 22:50:14 INFO - Main Display: Yes 22:50:14 INFO - Mirror: Off 22:50:14 INFO - Online: Yes 22:50:14 INFO - Rotation: Supported 22:50:14 INFO - Return code: 0 22:50:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:50:14 INFO - Python 2.7.3 22:50:14 INFO - Return code: 0 22:50:14 INFO - grabbing minidump binary from tooltool 22:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:50:14 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 0x1077a2d30>, '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 0x1075c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107799b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:50:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:50:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:50:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:50:14 INFO - Return code: 0 22:50:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:50:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:50:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:50:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:50:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:50:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:50:15 INFO - Return code: 0 22:50:15 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/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0106', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:50:15 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/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0106 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:50:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wfkNGb6YXJ/Render', 22:50:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:50:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:50:15 INFO - 'HOME': '/Users/cltbld', 22:50:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:50:15 INFO - 'LOGNAME': 'cltbld', 22:50:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:50:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:15 INFO - 'MOZ_NO_REMOTE': '1', 22:50:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:50:15 INFO - 'NO_EM_RESTART': '1', 22:50:15 INFO - 'PAGER': '/bin/cat', 22:50:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:50:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:15 INFO - 'PWD': '/builds/slave/test', 22:50:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:50:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:50:15 INFO - 'SHELL': '/bin/bash', 22:50:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.19LWI3E5Ov/Listeners', 22:50:15 INFO - 'TMPDIR': '/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/', 22:50:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:50:15 INFO - 'USER': 'cltbld', 22:50:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:50:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:15 INFO - 'XPC_FLAGS': '0x0', 22:50:15 INFO - 'XPC_SERVICE_NAME': '0', 22:50:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:50:15 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/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0106', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:50:16 INFO - mozversion application_buildid: 20160302204221 22:50:16 INFO - mozversion application_changeset: 2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3 22:50:16 INFO - mozversion application_display_name: Nightly 22:50:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:50:16 INFO - mozversion application_name: Firefox 22:50:16 INFO - mozversion application_remotingname: firefox 22:50:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:50:16 INFO - mozversion application_vendor: Mozilla 22:50:16 INFO - mozversion application_version: 47.0a1 22:50:16 INFO - mozversion platform_buildid: 20160302204221 22:50:16 INFO - mozversion platform_changeset: 2eb7b3fd4cc0ea0bdf666b63dad25bc74289aba3 22:50:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:50:16 INFO - mozversion platform_version: 47.0a1 22:50:16 INFO - using testdate: 1456987816 22:50:16 INFO - actual date: 1456987816 22:50:16 INFO - starting webserver on 'localhost:62555' 22:50:16 INFO - SUITE-START | Running 2 tests 22:50:16 INFO - TEST-START | dromaeo_css 22:50:16 INFO - operating with platform_type : mac_ 22:50:16 INFO - Initialising browser for dromaeo_css test... 22:50:16 INFO - TEST-INFO | started process 3616 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpfgkyLd/profile http://localhost:62555/getInfo.html) 22:50:18 INFO - PROCESS | 3616 | __metrics Screen width/height:1600/1200 22:50:18 INFO - PROCESS | 3616 | colorDepth:24 22:50:18 INFO - PROCESS | 3616 | Browser inner width/height: 1024/689 22:50:18 INFO - PROCESS | 3616 | __metrics 22:50:18 INFO - PROCESS | 3616 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:50:18 INFO - PROCESS | 3616 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:50:18 INFO - TEST-INFO | 3616: exit 0 22:50:18 INFO - Browser initialized. 22:50:18 INFO - Running cycle 1/1 for dromaeo_css test... 22:50:18 INFO - TEST-INFO | started process 3619 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpfgkyLd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:50:31 INFO - PROCESS | 3619 | RSS: Main: 243924992 22:50:31 INFO - PROCESS | 3619 | 22:51:21 INFO - PROCESS | 3619 | 1456987881983 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:53:32 INFO - PROCESS | 3619 | Cycle 1(1): loaded http://localhost:62555/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62555/tests/dromaeo/cssquery-ext.html) 22:53:33 INFO - PROCESS | 3619 | RSS: Main: 364658688 22:53:33 INFO - PROCESS | 3619 | 22:56:36 INFO - PROCESS | 3619 | Cycle 1(1): loaded http://localhost:62555/tests/dromaeo/cssquery-ext.html (next: http://localhost:62555/tests/dromaeo/cssquery-jquery.html) 22:56:37 INFO - PROCESS | 3619 | RSS: Main: 373915648 22:56:37 INFO - PROCESS | 3619 | 22:59:39 INFO - PROCESS | 3619 | Cycle 1(1): loaded http://localhost:62555/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62555/tests/dromaeo/cssquery-mootools.html) 22:59:39 INFO - PROCESS | 3619 | RSS: Main: 375160832 22:59:39 INFO - PROCESS | 3619 | 23:02:41 INFO - PROCESS | 3619 | Cycle 1(1): loaded http://localhost:62555/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62555/tests/dromaeo/cssquery-prototype.html) 23:02:42 INFO - PROCESS | 3619 | RSS: Main: 331587584 23:02:42 INFO - PROCESS | 3619 | 23:05:45 INFO - PROCESS | 3619 | Cycle 1(1): loaded http://localhost:62555/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62555/tests/dromaeo/cssquery-yui.html) 23:05:46 INFO - PROCESS | 3619 | RSS: Main: 334315520 23:05:46 INFO - PROCESS | 3619 | 23:08:49 INFO - PROCESS | 3619 | Cycle 1(1): loaded http://localhost:62555/tests/dromaeo/cssquery-yui.html (next: http://localhost:62555/tests/dromaeo/cssquery-dojo.html) 23:08:49 INFO - PROCESS | 3619 | RSS: Main: 379293696 23:08:49 INFO - PROCESS | 3619 | 23:08:49 INFO - PROCESS | 3619 | __start_tp_report 23:08:49 INFO - PROCESS | 3619 | _x_x_mozilla_page_load 23:08:49 INFO - PROCESS | 3619 | _x_x_mozilla_page_load_details 23:08:49 INFO - PROCESS | 3619 | |i|pagename|runs| 23:08:49 INFO - PROCESS | 3619 | |0;dojo.html;3363.108776174314;3397.2186397128658;3436.9656303436964;3444.3455743408736;3481.442969124939;5531.889362212763;5616.382197958222;5619.83140505785;5630.352509461408;5650.434956504353;5666.235058267132;5667.149927510875;5700.7719691212405;5807.070868661015;5823.184754134414;5097.898042039157;5119.720069982504;5130.486951304877;5153.2785410042625;5255.789768409259;5195.636305458601;5224.817131400402;5283.841484755463;5288.233206185101;5320.574354051667;5091.643584949055;5106.693598384109;5166.07010738067;5207.427183009866;5240.764165612557;5136.04983078128;5145.25393817898;5182.896342073137;5183.352080989876;5244.527992480657;5099.796008159669;5147.433782283946;5173.611979101583;5182.2485739567555;5189.948100518985;4579.542045795427;5475.151351540518;5598.636088654224;5602.747876345574;5664.291963504562;6146.446819786196;6153.1693221415435;6169.167162433059;6181.289151747516;6186.690665466709;6204.7828326008375;6222.937770622281;6230.501559875199;6235.7505699771955;6262.373762623747;5671.120976248647;5694.971525142348;5708.1723150143;5718.828435146952;5723.255976723041;6261.530385221126;6263.404976527223;6263.498920086382;6266.811995640138;6271.843203919939;4500.909981800346;5845.707714614252;5891.351951285441;5969.701514924323;5971.581989260797;6060.757569697163;6125.84685382869;6130.29501607312;6152.923238433345;6160.075988601746;6099.461111717566;6120.66336351496;6144.139820425144;6160.44555989963;6171.907421388682;6132.436228761637;6148.415900489446;6149.7847575335545;6157.476614487729;6159.1069294951985;4777.784999675023;4851.830185943546;4854.271859221145;4854.878628034327;4891.633127515451;4349.521552629209;4357.9564204358185;4443.133588950123;4450.087732014929;4453.755043472575;5502.119660854244;5566.081596536607;5578.2748242728185;5589;5593.049181639131;5062.746862656926;6118.596368888929;6123.343628574293;6131.040261761288;6153.507719382439;5549;5604.271444712243;5624.100143976944;5660.830175094754;5662.518685911661;5294.814681486207;5303.4696530346655;5306.389765176977;5311.28297679816;5322.840314790485;6258.530610204253;6273.843153921189;6291.905621415683;6295.307516173218;6300.054991751274;6104.084387341934;6162.7534898605345;6234.407731265523;6259;6281.340459251713;6243;6272.5922815016875;6283.5287353449585;6288.811335659938;6289.685515724287;5952.910706339322;6056.212692349966;6064.181335519648;6073.271207455134;6084.6349219047;6051.940910340761;6129.48104857405;6134.252905685562;6158.969205154125;6251.906221406592;6016.368281330398;6023.36754640774;6050.848728781815;6072.939270607414;6098.390160984043;5847.093700476433;6003.279606447254;6020.698965051817;6038.335783063947;6039.818805435844;5754.309482862083;5817.331006934257;5881.17663527098;5887.293524777054;5919.052951527735;6006.429389208019;6030.728617212324;6048.939510604838;6062.969685151546;6081.543884208613;5750.712464376848;5856.385079566755;5886.882262354815;5913.940860591339;5941.643501389931 23:08:49 INFO - PROCESS | 3619 | |1;ext.html;485.4223474065859;572.8857372410661;574.7729646789518;625.612120485299;634.5780056262586;37128.81435592825;37688.62311376885;37700.62299377002;37715.43426848596;37815.810920945354;40056;40742.79628601853;40755.38866916999;40755.796221018856;40773.59226407735;9409.717708468757;9633.325667203299;9650.420974741503;9651.71044868655;9662.227021838271;9442.150206481416;9587.424754514717;9596.472194029326;9605.519724013806;9608.327417080807;9614.13472787449;9664.178544823699;9679.27405444591;9688.321817472779;9744.707658770249;9629.903700963006;9715.27135464836;9718.708438746813;9729.221662267002;9738.269629777724;9575.99452057538;9700.563474643657;9716.805663886682;9717.271204659679;9727.610895564168;38855;39174.21651566953;39212.80393598014;39250.60749392498;39251.82244532669;2993.1918382036947;3128.6871312868757;3256.0971384163163;3391.321735652855;3717.7211709121652;26646.467070658673;26851.86574067137;27554.311142221406;27561;27604.033858814844;2660.162048954573;2693.2189664997322;2778.9440012795008;2829.773618110567;2830.6886242513306;393943;397888;398375;398528;399190;60344;63499;63531.72936541243;63567;63652.04521932175;67543.32456675469;69083;69090;69162.3083769156;69176.96234556485;16384;16544.090075045988;16578.917105414395;16629.91905526137;16661.91669041647;12743.04427167966;12777.977761779035;12789.61631151067;12819.743605128031;12834.614961551168;19215.231390744495;20353.287634933004;20363.490912727004;20381.796182038288;20398.79601203969;8929.374943753875;9091.909080909107;9096.954515227382;9123.726288211365;9125.954370228106;726.9709211631475;927.4898805656861;937.6015193542717;1077.849101125843;1122.910167186623;23702;28118.859405702842;28368.744758176897;28869.422611547652;29034.854825725735;823.3660081737029;1076.3703233608426;1108.5177947592822;1112.3159257056852;1119.9552017919193;10423.895761042444;14505.171491559257;14546.4908728194;14650.878396516275;14781.556553303419;509537.4523127361;511265;514057;515390;516328;55542.16686749702;56374;56391.43608563862;56403.71798140983;56447.71776141093;1255.8932490738207;1391.8399384070758;1413.6677880698028;1426.2868565717142;1542.2211783049488;27640.723592764618;27673.723262767115;28295.434091317646;28338.2915427116;29330.266743331587;45570.31644525401;45726.31410528778;46110.7694461539;46133.30800037935;46144.769276153405;366246;367595;367743;368819;369836;27704.168874933785;31435.371292574477;32603.510947335333;32655.673443266212;32676.83661581677;39682.80158599189;40283.59716402798;40341.59658403378;40632.30913779695;41337.96655083647;35057.82471087628;35238.647613523535;35931.28137437289;36059.09852253715;36188;34550.96347109591;34752.47871281984;35013.649863502054;35414;35444.645553544135;34507.654923451446;34790;35018.12454688653;35150.12124696903;35499.467507987916;22406.32781016572;30433.543496847124;30963.342881279732;30984.380312393176;31045.68954310428;23306.3008109757;23674.171404000776;23674.881625591763;23818.88090559536;23819.88090059608 23:08:49 INFO - PROCESS | 3619 | |2;jquery.html;347.35045464980504;347.5950517646941;348.53644652612013;349.577011815703;356.8269389346168;15275.923620381911;15647.374105035786;19196.040197990096;19223.32718354856;20874.26940057098;18859.811401885978;18905.90547047263;19325.613487730236;19787.703184452246;19835.504112397197;5581.804636837691;5586.86032849178;5766.596338256324;5799.536037117021;5859.7656093756195;3826.8469261229516;5620.044592419298;5714.742836572344;5736.996025695486;5767.134929760548;5837.854053648672;5838.9124163137585;5840.415958404168;5845.269341332333;5850.88298234035;5806.564507661942;5809.970950145264;5817.796377126801;6285;6356.96821515894;5629;5633.521150702195;5637.154426835967;5642.774289028475;5646.447227012563;12806.679833004157;16979.660406791918;21886.671699924515;24762.628560571608;24786.628200577004;2053.3531937439657;2085.5620319732875;2106.9157233710635;2149.613069647463;2241.57410092082;9066.456012639197;9650.75873103171;9716.90283097167;9860.704178874645;9926.602935882556;1640.286475383212;1718.785151856018;1722.7243641017376;1730.126286225448;1791.6774980503503;106718.46640766824;109644.90355096427;110351;113892.72214555662;114004.85995140026;9973.700788976343;10877.456127193609;11032.889671103187;11045.226834121695;11082.889171108185;12149.635510934686;12509.49962001528;13458.394372253271;13588.93205533966;13597.728045439037;8258;8270.627821748036;8300;8522.957385213034;8525.74422767318;8325.666973321;8365.498070115813;8512.106228846005;8589.098144694843;8608;4338;4428.22506061438;4447.666425018137;4474.485434175112;4485.0805584855025;1814.7640806695044;1859.5072305838964;1864.2356633780084;1867.757191565115;1871.1579789094963;823.9134890836376;834.3950635789125;916.4684482999844;991.4398364923795;1008.0020779428313;14835;19227;19436.319728809707;19745.901270493556;19809;911.3119594705954;912.9045145824904;968.4770224079059;971.6356366362614;1020.882598501167;10579.894201058045;10804.891951080546;10825.296355736851;10832.079273261861;10878.129749620011;447957;456531;456951;623118;627866;125587.70839882825;126376.36811815882;131687;132446.33776831246;132886;1007.5617106558524;1038.5170895533463;1044.3733759744246;1047.5024363427463;1048.8567461467037;14869.851301487279;18096.72854907204;18217.271309147487;18349;19008.42974710761;39057.609423906535;39568.80215598903;50624;50697.493025070755;51595.484045159064;66303.66848165728;66886.99669505059;71163.93254101089;75163;75283.62358188174;7590.962045189739;7824.295813376823;7937.960310198412;8053.1946805319;8202.671893124209;11357.602483913024;12612.432440540015;12941;13137;13156.013299002421;10891.945540272249;11057.83413248818;11302.830457543309;11433.428328583372;11515.25150865191;8942.50816205115;10830.51262693165;11382.203245772716;11480.540778368771;11892.76214475723;11547.480363383836;11556.248843825124;11739.530418783495;11924.940375298414;11962.58130965412;6877.899536074204;6957.199922009034;7860.960695196487;7883.211678832071;8344.666213351398;11061.944690276498;11379.658610241706;11415.828762568734;11464.140189485655;11813.763724725286 23:08:49 INFO - PROCESS | 3619 | |3;mootools.html;1417.1922004457463;1523.4591719939422;1550.7053659804633;1765.788105427349;1929.7877233504312;7821.608919554028;8437.240648341649;8567.18611731886;8604.225619694227;8617.224449799516;8323.87741126012;8540.444871083375;8557.486550806956;8562.357823163256;8567.742967710963;2102.7581828089815;2379.0602711928777;2415.323709361377;2440.6339049142593;2443.58459061959;2364.645303204516;2371.976280237193;2372.122314743547;2373.2524254859663;2374.620060790274;2225.321277010511;2243.809276211524;2305.965410518844;2306.8615883046987;2312;2336.3458231695154;2337.0418128567208;2353.5057637895893;2448.277758061376;2449;2329.895154718042;2359.2332491940188;2361.2562042956415;2363.5745565798147;2455.6316552517087;8609.69866054685;8679;8689.17452841979;8696.739097827076;8710.782230444227;5281.683099014072;5380.4350543192795;5408.242846001563;5419.810306639288;5428.61999660024;3528.858845646171;3597.6042635310096;3659.1218107654236;3668.046307959923;3676.117731744389;1032.1587905856702;1048.5334026358273;1051.7318083888636;1052.3948729480503;1066.3441983180367;11677.065834733201;11679.591214307544;11686.298822070703;11695.35675537845;11714.472848721827;3834.789086600208;4606.193916064743;4724.149653062413;4807.278908163734;4850.320955066293;10032.996700329908;10037.64868229623;10042.397456152654;10047.698569042941;10067.295289329677;5311.893762124813;5362.00802851466;5373.65071270374;5378.220158077135;5383.650062745909;4328.09110086879;4510.909781804346;4668.996165824364;4775.6657034007985;4778.904421911542;3789.905252368713;3877.9224415512094;3887.6501114899793;3920.2943470175073;3954.5847685992558;1251.217988757027;1256.1018871506917;1256.6292943581666;1267.8541967673837;1271.675722690708;1100.8183649697871;1110.611286049876;1113.2207454781685;1243;1352.2900477249523;7857.1357150713375;7999.520028798291;8045.477043992122;8077.232662897015;8081.353491720648;482.09606986899564;496.2704823908848;498.9027139707722;500.3945226276174;502.1689104532005;6105.938940610626;6178.783742568989;6183.103449999725;6183.752649894044;6198.907016394758;10091.243156763272;10213.182945364502;10226.795464090677;10312.968703129627;10357.63748268807;9271.629134834666;10041.849372259421;10176.440295783656;10198.643047493451;10199.28604997658;6932.896006559906;7940.245676660708;8109.472884262504;8149.877751833727;8181.550014749127;7727;7830.373570114263;7924.286814186751;7927.682892684228;8070.2333278338465;8913.866292005496;9256.028117047614;9420.199283061016;9522.23822094217;9534.332596718163;9326.347155699308;9434.905650943403;9575.94664586881;9594.13652771226;9744.951275243578;6723.159605049369;6927.7228910845015;7162.427005839416;7334.559926404433;7481.738139165103;8089.393295502979;8127.431079824592;8176.386770992318;8180.018397792303;8191.221833925768;7094.4679149062995;7164.928350716635;7180.712771489291;7307.744228951961;7342.265773422615;7046.964765176142;7060.82347941285;7165.749198778018;7208.207097219197;7224.349808517259;7292.570755893313;7513.2862378074;7514.699412023677;7558.13081495613;7573.734919278019;6855.417289530246;6857.177138743383;6889.793306200822;6901.240863505111;6909.1018167637885;6868.347506986828;6990.161180658353;6992.685329160098;6994.5803251805055;7004.194517630538 23:08:49 INFO - PROCESS | 3619 | |4;prototype.html;304.85824091797315;309.13288226524605;310.90672798160546;312.2085513223977;314.70103401768324;6522.9021564676505;6609.702563384647;6729.865402691943;6757.966210168942;6759.256481787;6580.045893345474;6619.17260342456;6671.432928201097;6672;6682.264950855414;3723.609021052791;4592.163134746095;4742.881427964294;4874.195757699975;4875.463698993117;3720.5163328767358;4781.780645935281;4796.808127674889;4819.1325561398935;4828.493008234138;3915.7650540967493;4677.830542364409;4731.053789242148;4740;4752.07334569759;3825;4549.863504094882;4627.1439783640135;4749.073930583503;4773.379460670126;4037.7577345359077;4040.4949381327333;4047.1298670785923;4047.947533641245;4054.553999060101;6318.810435686937;6369.490440764774;6378.87242255157;6385.138006369174;6402.423781859608;5524.447555244484;5527.53015993641;5539.529140023103;5540.722963851832;5542.307211598551;6239.7208572243035;6965.686544105527;6978.162620485524;6980.95285707148;6986.40615547679;4128.348660535809;4261.786910654486;4444.555544445562;4453.732776033448;4457.48738895028;6656.93343066568;6693.899591506131;7206.639668016578;7307.744228951961;7424.703011879572;3737.2338670572467;4520.886977825515;4528.818847246139;4530.275155975094;4532.524084971031;4602.7468489233415;4607.516210797885;4615.2384856498975;4624.398828152386;4629.51390104041;4791.448983366888;5486.807961721321;5492.286002819608;5509.090999984957;5514.503694667499;5846.269216347957;6602.801915942529;7027.926761605237;7036.6129862857015;7044.189918159374;1646.6542026174639;1648.1100205888686;1661.2773443552094;1664.2427695398565;1667.7748503952055;1042.1766804224728;1067.1836045425257;1068.076114161245;1084.2139448899484;1099.6316234061678;5229.973850130801;6349.365063493614;6465.127207826978;6496;6508.642024688687;7371.557706537625;7375.631218439056;7376.409887209011;7376.967224588562;7379.048380647677;6571.474282057424;6584.539082264291;6588.209414870246;6595.439387652007;6595.439387652007;3311.9503207451908;3392.9481860623287;3841.5006049213425;3886.805659717003;3891.902702432405;7347.191808901017;7572.053493313336;7591.127020392725;7605.391568674493;7665.118511371264;7467.813304667427;7497.362724168507;7501.849963000818;7517.661705223278;7587.810304742426;6172.938270617326;6213.5339849510565;7652.7704168875025;7674;7675.078990521061;7342.926570734225;7345.632718363953;7350.411967042731;7362.006129172707;7362.153352364332;6962.7911162665205;7228.31331023552;7248.130224373109;7253.637318134178;7263.455240856852;7636.694532218871;7642.694292228249;7645.847083058419;7653.9234607655435;7654.2345765423015;4519.97740011311;4548.727076375428;4566.70316429431;4572.519885412117;4573.199690054294;7277.417806575355;7335.77992660221;7340.96329518349;7351.081114860643;7357.705691772269;6767.627780471925;7029.191642961125;7036.824079398056;7038.507304488637;7054.611996340251;6966.825829354307;7011.544249623758;7024.4029257513685;7052.365287124184;7085.574865508086;6943.340382663639;6950.930490695028;6951.895721564079;6965.442764578923;6965.885458326644;6829.146356705412;7092.468064895051;7104.218535960937;7111.893321600077;7146.32109949554;6765.763198288037;6835.179778426422;6863.450923926173;6949.305069493213;6986.755463558751 23:08:49 INFO - PROCESS | 3619 | |5;yui.html;648.455297550058;654.9345065493451;655.790147152911;677.840707433753;682.1302838880424;14519.564413067625;14591.635209119777;14822.777658335133;14830.258487075656;15170.54488365348;13749.931250343734;13772.449102035907;13815.240161791098;13816.309184540783;13820.861791382084;715.8389362393472;724.6847621284732;835.757630287216;850.200811237438;863.0894406401244;867.2758246863876;879.8592225243963;880.106691707917;882.3294296334767;883.0771843423638;910.5401772105077;923.4782347973407;923.9353245272833;924.1036194890945;924.3206243411088;740.1007775552703;891.4116682989261;908.2688435809171;917.4449458077834;919.4850883505259;669.1134247122582;675.4866301610812;679.7077256779583;679.7383007542082;680.2924958043628;14397.280135993158;14429.134251944917;14459;14464.13215207091;14469.987100902941;12292.877071229352;12359.382030898509;12404.689882752913;12476.875231247663;12650.367481625883;9736.561854716554;9936.25478089139;9944.950275248577;9974.750631234278;10248.333858299187;3423.3153369326233;3454.9309013819834;3485.825708714554;3498.772579782306;3575.284943011382;79997.60001200016;80033.99999999942;80314.19685803125;80349.59825200895;80417.3916521672;8931.687390941286;9170.174684278447;9194.862077068849;9402.576884040234;9516.571754271074;17090.230939607747;20284.79715202829;20628.69056964147;20651.69022464664;20687.482812929797;7247.673854676552;7261.346478816826;7304.890426643605;7448.962755186298;7502.587357695379;3959.92080158401;4018.819153138116;4079.8572049978106;4085.55058943516;4146.792660366969;2801.817881837674;3250.9596928982974;3470.878519251814;3534.7702399344475;3543.450765131409;526.5471694342862;610.4170517156106;615.8944694273789;629.6662768732579;636.5257882877286;538.4454012367268;573.9081746920473;577.8459407312076;600.4445887554083;608.5496732417979;7983.560904150328;8108.39187060961;8216.876746848802;8257.587120644064;8291.461055031403;493.2527221259794;516.5273774496369;559.7405836867049;594.5013594993494;601.4707057789118;3611.6207798180812;3800.619938006177;3808.961910380861;3875.496185495924;3884.553276373253;93731.53134234421;97625.51187244018;99752;99916.50041749745;101646;80038;82400;82806.75789863156;83185.58407207925;83759.3248135046;69947.30052699408;70493.64753176304;70655.29344706488;70952;71070;7988.600569971362;8332.333413326798;8359.414840961075;8541.231289184003;8607.053224145213;47308;47653.761731192506;48662.5133748658;50423.74788126183;51456.74271628768;65933;67456.6627166861;69020;70147.29852701408;71290.64354678194;10837.620683276364;11135;11219.831702524632;11253.71865703364;11528.308301501937;11881.881181188422;12011.699707507381;12129.150959432754;12296.016318694305;12312.50749970027;11309.830352544883;11478.426078696199;11574.18980671345;11594.826077608674;11619.037145527836;11094.334339939629;11331.546738130382;11492.770144597229;11638.825417618911;11642.70893227676;11045.226834121535;11163.44182790841;11181.440927953407;11264.493097810455;11274.323540587591;9165.266778657558;9261.16649501548;9266.744947160774;9322.394044387092;9857.211423086279;9161;9374.25005999532;9398.2951278653;9874.62144457242;10048 23:08:49 INFO - PROCESS | 3619 | __end_tp_report 23:08:49 INFO - PROCESS | 3619 | __start_cc_report 23:08:49 INFO - PROCESS | 3619 | _x_x_mozilla_cycle_collect,1129 23:08:49 INFO - PROCESS | 3619 | __end_cc_report 23:08:49 INFO - PROCESS | 3619 | __startTimestamp1456988929756__endTimestamp 23:08:49 INFO - PROCESS | 3619 | 23:08:49 INFO - PROCESS | 3619 | ------- Summary: start ------- 23:08:49 INFO - PROCESS | 3619 | Number of tests: 6 23:08:49 INFO - PROCESS | 3619 | 23:08:49 INFO - PROCESS | 3619 | [#0] dojo.html Cycles:165 Average:5664.26 Median:5856.39 stddev:628.69 (10.7%) stddev-sans-first:604.14 23:08:49 INFO - PROCESS | 3619 | Values: 3363.1 3397.2 3437.0 3444.3 3481.4 5531.9 5616.4 5619.8 5630.4 5650.4 5666.2 5667.1 5700.8 5807.1 5823.2 5097.9 5119.7 5130.5 5153.3 5255.8 5195.6 5224.8 5283.8 5288.2 5320.6 5091.6 5106.7 5166.1 5207.4 5240.8 5136.0 5145.3 5182.9 5183.4 5244.5 5099.8 5147.4 5173.6 5182.2 5189.9 4579.5 5475.2 5598.6 5602.7 5664.3 6146.4 6153.2 6169.2 6181.3 6186.7 6204.8 6222.9 6230.5 6235.8 6262.4 5671.1 5695.0 5708.2 5718.8 5723.3 6261.5 6263.4 6263.5 6266.8 6271.8 4500.9 5845.7 5891.4 5969.7 5971.6 6060.8 6125.8 6130.3 6152.9 6160.1 6099.5 6120.7 6144.1 6160.4 6171.9 6132.4 6148.4 6149.8 6157.5 6159.1 4777.8 4851.8 4854.3 4854.9 4891.6 4349.5 4358.0 4443.1 4450.1 4453.8 5502.1 5566.1 5578.3 5589.0 5593.0 5062.7 6118.6 6123.3 6131.0 6153.5 5549.0 5604.3 5624.1 5660.8 5662.5 5294.8 5303.5 5306.4 5311.3 5322.8 6258.5 6273.8 6291.9 6295.3 6300.1 6104.1 6162.8 6234.4 6259.0 6281.3 6243.0 6272.6 6283.5 6288.8 6289.7 5952.9 6056.2 6064.2 6073.3 6084.6 6051.9 6129.5 6134.3 6159.0 6251.9 6016.4 6023.4 6050.8 6072.9 6098.4 5847.1 6003.3 6020.7 6038.3 6039.8 5754.3 5817.3 5881.2 5887.3 5919.1 6006.4 6030.7 6048.9 6063.0 6081.5 5750.7 5856.4 5886.9 5913.9 5941.6 23:08:49 INFO - PROCESS | 3619 | 23:08:49 INFO - PROCESS | 3619 | [#1] ext.html Cycles:180 Average:57622.19 Median:20423.27 stddev:114306.52 (559.7%) stddev-sans-first:114546.68 23:08:49 INFO - PROCESS | 3619 | Values: 485.4 572.9 574.8 625.6 634.6 37128.8 37688.6 37700.6 37715.4 37815.8 40056.0 40742.8 40755.4 40755.8 40773.6 9409.7 9633.3 9650.4 9651.7 9662.2 9442.2 9587.4 9596.5 9605.5 9608.3 9614.1 9664.2 9679.3 9688.3 9744.7 9629.9 9715.3 9718.7 9729.2 9738.3 9576.0 9700.6 9716.8 9717.3 9727.6 38855.0 39174.2 39212.8 39250.6 39251.8 2993.2 3128.7 3256.1 3391.3 3717.7 26646.5 26851.9 27554.3 27561.0 27604.0 2660.2 2693.2 2778.9 2829.8 2830.7 393943.0 397888.0 398375.0 398528.0 399190.0 60344.0 63499.0 63531.7 63567.0 63652.0 67543.3 69083.0 69090.0 69162.3 69177.0 16384.0 16544.1 16578.9 16629.9 16661.9 12743.0 12778.0 12789.6 12819.7 12834.6 19215.2 20353.3 20363.5 20381.8 20398.8 8929.4 9091.9 9097.0 9123.7 9126.0 727.0 927.5 937.6 1077.8 1122.9 23702.0 28118.9 28368.7 28869.4 29034.9 823.4 1076.4 1108.5 1112.3 1120.0 10423.9 14505.2 14546.5 14650.9 14781.6 509537.5 511265.0 514057.0 515390.0 516328.0 55542.2 56374.0 56391.4 56403.7 56447.7 1255.9 1391.8 1413.7 1426.3 1542.2 27640.7 27673.7 28295.4 28338.3 29330.3 45570.3 45726.3 46110.8 46133.3 46144.8 366246.0 367595.0 367743.0 368819.0 369836.0 27704.2 31435.4 32603.5 32655.7 32676.8 39682.8 40283.6 40341.6 40632.3 41338.0 35057.8 35238.6 35931.3 36059.1 36188.0 34551.0 34752.5 35013.6 35414.0 35444.6 34507.7 34790.0 35018.1 35150.1 35499.5 22406.3 30433.5 30963.3 30984.4 31045.7 23306.3 23674.2 23674.9 23818.9 23819.9 23:08:49 INFO - PROCESS | 3619 | 23:08:49 INFO - PROCESS | 3619 | [#2] jquery.html Cycles:180 Average:31971.70 Median:12018.14 stddev:88927.26 (739.9%) stddev-sans-first:89145.02 23:08:49 INFO - PROCESS | 3619 | Values: 347.4 347.6 348.5 349.6 356.8 15275.9 15647.4 19196.0 19223.3 20874.3 18859.8 18905.9 19325.6 19787.7 19835.5 5581.8 5586.9 5766.6 5799.5 5859.8 3826.8 5620.0 5714.7 5737.0 5767.1 5837.9 5838.9 5840.4 5845.3 5850.9 5806.6 5810.0 5817.8 6285.0 6357.0 5629.0 5633.5 5637.2 5642.8 5646.4 12806.7 16979.7 21886.7 24762.6 24786.6 2053.4 2085.6 2106.9 2149.6 2241.6 9066.5 9650.8 9716.9 9860.7 9926.6 1640.3 1718.8 1722.7 1730.1 1791.7 106718.5 109644.9 110351.0 113892.7 114004.9 9973.7 10877.5 11032.9 11045.2 11082.9 12149.6 12509.5 13458.4 13588.9 13597.7 8258.0 8270.6 8300.0 8523.0 8525.7 8325.7 8365.5 8512.1 8589.1 8608.0 4338.0 4428.2 4447.7 4474.5 4485.1 1814.8 1859.5 1864.2 1867.8 1871.2 823.9 834.4 916.5 991.4 1008.0 14835.0 19227.0 19436.3 19745.9 19809.0 911.3 912.9 968.5 971.6 1020.9 10579.9 10804.9 10825.3 10832.1 10878.1 447957.0 456531.0 456951.0 623118.0 627866.0 125587.7 126376.4 131687.0 132446.3 132886.0 1007.6 1038.5 1044.4 1047.5 1048.9 14869.9 18096.7 18217.3 18349.0 19008.4 39057.6 39568.8 50624.0 50697.5 51595.5 66303.7 66887.0 71163.9 75163.0 75283.6 7591.0 7824.3 7938.0 8053.2 8202.7 11357.6 12612.4 12941.0 13137.0 13156.0 10891.9 11057.8 11302.8 11433.4 11515.3 8942.5 10830.5 11382.2 11480.5 11892.8 11547.5 11556.2 11739.5 11924.9 11962.6 6877.9 6957.2 7861.0 7883.2 8344.7 11061.9 11379.7 11415.8 11464.1 11813.8 23:08:49 INFO - PROCESS | 3619 | 23:08:49 INFO - PROCESS | 3619 | [#3] mootools.html Cycles:180 Average:5893.50 Median:5336.95 stddev:3129.18 (58.6%) stddev-sans-first:3119.87 23:08:49 INFO - PROCESS | 3619 | Values: 1417.2 1523.5 1550.7 1765.8 1929.8 7821.6 8437.2 8567.2 8604.2 8617.2 8323.9 8540.4 8557.5 8562.4 8567.7 2102.8 2379.1 2415.3 2440.6 2443.6 2364.6 2372.0 2372.1 2373.3 2374.6 2225.3 2243.8 2306.0 2306.9 2312.0 2336.3 2337.0 2353.5 2448.3 2449.0 2329.9 2359.2 2361.3 2363.6 2455.6 8609.7 8679.0 8689.2 8696.7 8710.8 5281.7 5380.4 5408.2 5419.8 5428.6 3528.9 3597.6 3659.1 3668.0 3676.1 1032.2 1048.5 1051.7 1052.4 1066.3 11677.1 11679.6 11686.3 11695.4 11714.5 3834.8 4606.2 4724.1 4807.3 4850.3 10033.0 10037.6 10042.4 10047.7 10067.3 5311.9 5362.0 5373.7 5378.2 5383.7 4328.1 4510.9 4669.0 4775.7 4778.9 3789.9 3877.9 3887.7 3920.3 3954.6 1251.2 1256.1 1256.6 1267.9 1271.7 1100.8 1110.6 1113.2 1243.0 1352.3 7857.1 7999.5 8045.5 8077.2 8081.4 482.1 496.3 498.9 500.4 502.2 6105.9 6178.8 6183.1 6183.8 6198.9 10091.2 10213.2 10226.8 10313.0 10357.6 9271.6 10041.8 10176.4 10198.6 10199.3 6932.9 7940.2 8109.5 8149.9 8181.6 7727.0 7830.4 7924.3 7927.7 8070.2 8913.9 9256.0 9420.2 9522.2 9534.3 9326.3 9434.9 9575.9 9594.1 9745.0 6723.2 6927.7 7162.4 7334.6 7481.7 8089.4 8127.4 8176.4 8180.0 8191.2 7094.5 7164.9 7180.7 7307.7 7342.3 7047.0 7060.8 7165.7 7208.2 7224.3 7292.6 7513.3 7514.7 7558.1 7573.7 6855.4 6857.2 6889.8 6901.2 6909.1 6868.3 6990.2 6992.7 6994.6 7004.2 23:08:49 INFO - PROCESS | 3619 | 23:08:49 INFO - PROCESS | 3619 | [#4] prototype.html Cycles:180 Average:5688.80 Median:6575.76 stddev:1874.76 (28.5%) stddev-sans-first:1835.95 23:08:49 INFO - PROCESS | 3619 | Values: 304.9 309.1 310.9 312.2 314.7 6522.9 6609.7 6729.9 6758.0 6759.3 6580.0 6619.2 6671.4 6672.0 6682.3 3723.6 4592.2 4742.9 4874.2 4875.5 3720.5 4781.8 4796.8 4819.1 4828.5 3915.8 4677.8 4731.1 4740.0 4752.1 3825.0 4549.9 4627.1 4749.1 4773.4 4037.8 4040.5 4047.1 4047.9 4054.6 6318.8 6369.5 6378.9 6385.1 6402.4 5524.4 5527.5 5539.5 5540.7 5542.3 6239.7 6965.7 6978.2 6981.0 6986.4 4128.3 4261.8 4444.6 4453.7 4457.5 6656.9 6693.9 7206.6 7307.7 7424.7 3737.2 4520.9 4528.8 4530.3 4532.5 4602.7 4607.5 4615.2 4624.4 4629.5 4791.4 5486.8 5492.3 5509.1 5514.5 5846.3 6602.8 7027.9 7036.6 7044.2 1646.7 1648.1 1661.3 1664.2 1667.8 1042.2 1067.2 1068.1 1084.2 1099.6 5230.0 6349.4 6465.1 6496.0 6508.6 7371.6 7375.6 7376.4 7377.0 7379.0 6571.5 6584.5 6588.2 6595.4 6595.4 3312.0 3392.9 3841.5 3886.8 3891.9 7347.2 7572.1 7591.1 7605.4 7665.1 7467.8 7497.4 7501.8 7517.7 7587.8 6172.9 6213.5 7652.8 7674.0 7675.1 7342.9 7345.6 7350.4 7362.0 7362.2 6962.8 7228.3 7248.1 7253.6 7263.5 7636.7 7642.7 7645.8 7653.9 7654.2 4520.0 4548.7 4566.7 4572.5 4573.2 7277.4 7335.8 7341.0 7351.1 7357.7 6767.6 7029.2 7036.8 7038.5 7054.6 6966.8 7011.5 7024.4 7052.4 7085.6 6943.3 6950.9 6951.9 6965.4 6965.9 6829.1 7092.5 7104.2 7111.9 7146.3 6765.8 6835.2 6863.5 6949.3 6986.8 23:08:49 INFO - PROCESS | 3619 | 23:08:49 INFO - PROCESS | 3619 | [#5] yui.html Cycles:180 Average:18492.86 Median:622.78 stddev:26540.32 (4261.6%) stddev-sans-first:26580.96 23:08:49 INFO - PROCESS | 3619 | Values: 648.5 654.9 655.8 677.8 682.1 14519.6 14591.6 14822.8 14830.3 15170.5 13749.9 13772.4 13815.2 13816.3 13820.9 715.8 724.7 835.8 850.2 863.1 867.3 879.9 880.1 882.3 883.1 910.5 923.5 923.9 924.1 924.3 740.1 891.4 908.3 917.4 919.5 669.1 675.5 679.7 679.7 680.3 14397.3 14429.1 14459.0 14464.1 14470.0 12292.9 12359.4 12404.7 12476.9 12650.4 9736.6 9936.3 9945.0 9974.8 10248.3 3423.3 3454.9 3485.8 3498.8 3575.3 79997.6 80034.0 80314.2 80349.6 80417.4 8931.7 9170.2 9194.9 9402.6 9516.6 17090.2 20284.8 20628.7 20651.7 20687.5 7247.7 7261.3 7304.9 7449.0 7502.6 3959.9 4018.8 4079.9 4085.6 4146.8 2801.8 3251.0 3470.9 3534.8 3543.5 526.5 610.4 615.9 629.7 636.5 538.4 573.9 577.8 600.4 608.5 7983.6 8108.4 8216.9 8257.6 8291.5 493.3 516.5 559.7 594.5 601.5 3611.6 3800.6 3809.0 3875.5 3884.6 93731.5 97625.5 99752.0 99916.5 101646.0 80038.0 82400.0 82806.8 83185.6 83759.3 69947.3 70493.6 70655.3 70952.0 71070.0 7988.6 8332.3 8359.4 8541.2 8607.1 47308.0 47653.8 48662.5 50423.7 51456.7 65933.0 67456.7 69020.0 70147.3 71290.6 10837.6 11135.0 11219.8 11253.7 11528.3 11881.9 12011.7 12129.2 12296.0 12312.5 11309.8 11478.4 11574.2 11594.8 11619.0 11094.3 11331.5 11492.8 11638.8 11642.7 11045.2 11163.4 11181.4 11264.5 11274.3 9165.3 9261.2 9266.7 9322.4 9857.2 9161.0 9374.3 9398.3 9874.6 10048.0 23:08:49 INFO - PROCESS | 3619 | -------- Summary: end -------- 23:08:49 INFO - PROCESS | 3619 | 23:08:50 INFO - TEST-INFO | 3619: exit 0 23:08:50 INFO - TEST-OK | dromaeo_css | took 1114163ms 23:08:50 INFO - TEST-START | kraken 23:08:50 INFO - operating with platform_type : mac_ 23:08:50 INFO - Initialising browser for kraken test... 23:08:50 INFO - TEST-INFO | started process 3621 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpanwiKT/profile http://localhost:62555/getInfo.html) 23:08:52 INFO - PROCESS | 3621 | __metrics Screen width/height:1600/1200 23:08:52 INFO - PROCESS | 3621 | colorDepth:24 23:08:52 INFO - PROCESS | 3621 | Browser inner width/height: 1024/689 23:08:52 INFO - PROCESS | 3621 | __metrics 23:08:52 INFO - PROCESS | 3621 | 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" 23:08:52 INFO - PROCESS | 3621 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:08:53 INFO - TEST-INFO | 3621: exit 0 23:08:53 INFO - Browser initialized. 23:08:53 INFO - Running cycle 1/1 for kraken test... 23:08:53 INFO - TEST-INFO | started process 3623 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/T/tmpanwiKT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:09:05 INFO - PROCESS | 3623 | RSS: Main: 243994624 23:09:05 INFO - PROCESS | 3623 | 23:09:07 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62555/tests/kraken/driver.html?testName=audio-beat-detection) 23:09:08 INFO - PROCESS | 3623 | RSS: Main: 290328576 23:09:08 INFO - PROCESS | 3623 | 23:09:11 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62555/tests/kraken/driver.html?testName=audio-dft) 23:09:11 INFO - PROCESS | 3623 | RSS: Main: 299835392 23:09:11 INFO - PROCESS | 3623 | 23:09:15 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62555/tests/kraken/driver.html?testName=audio-fft) 23:09:15 INFO - PROCESS | 3623 | RSS: Main: 284659712 23:09:15 INFO - PROCESS | 3623 | 23:09:17 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62555/tests/kraken/driver.html?testName=audio-oscillator) 23:09:18 INFO - PROCESS | 3623 | RSS: Main: 288002048 23:09:18 INFO - PROCESS | 3623 | 23:09:20 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62555/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:09:21 INFO - PROCESS | 3623 | RSS: Main: 289677312 23:09:21 INFO - PROCESS | 3623 | 23:09:23 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62555/tests/kraken/driver.html?testName=imaging-darkroom) 23:09:24 INFO - PROCESS | 3623 | RSS: Main: 290562048 23:09:24 INFO - PROCESS | 3623 | 23:09:27 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62555/tests/kraken/driver.html?testName=imaging-desaturate) 23:09:27 INFO - PROCESS | 3623 | RSS: Main: 289083392 23:09:27 INFO - PROCESS | 3623 | 23:09:30 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62555/tests/kraken/driver.html?testName=json-parse-financial) 23:09:31 INFO - PROCESS | 3623 | RSS: Main: 291397632 23:09:31 INFO - PROCESS | 3623 | 23:09:32 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62555/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:09:33 INFO - PROCESS | 3623 | RSS: Main: 323784704 23:09:33 INFO - PROCESS | 3623 | 23:09:35 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62555/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:09:35 INFO - PROCESS | 3623 | RSS: Main: 323080192 23:09:35 INFO - PROCESS | 3623 | 23:09:37 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62555/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:09:38 INFO - PROCESS | 3623 | RSS: Main: 331866112 23:09:38 INFO - PROCESS | 3623 | 23:09:41 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62555/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:09:41 INFO - PROCESS | 3623 | RSS: Main: 332451840 23:09:41 INFO - PROCESS | 3623 | 23:09:44 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62555/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:09:44 INFO - PROCESS | 3623 | RSS: Main: 333398016 23:09:44 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | Cycle 1(1): loaded http://localhost:62555/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62555/tests/kraken/driver.html?testName=ai-astar) 23:09:46 INFO - PROCESS | 3623 | RSS: Main: 393592832 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | __start_tp_report 23:09:46 INFO - PROCESS | 3623 | _x_x_mozilla_page_load 23:09:46 INFO - PROCESS | 3623 | _x_x_mozilla_page_load_details 23:09:46 INFO - PROCESS | 3623 | |i|pagename|runs| 23:09:46 INFO - PROCESS | 3623 | |0;ai-astar;111;110;112;112;112;113;113;110;110;109 23:09:46 INFO - PROCESS | 3623 | |1;audio-beat-detection;160;122;146;164;151;170;131;141;194;150 23:09:46 INFO - PROCESS | 3623 | |2;audio-dft;199;257;186;184;185;189;186;204;262;180 23:09:46 INFO - PROCESS | 3623 | |3;audio-fft;78;76;86;79;57;57;57;83;77;56 23:09:46 INFO - PROCESS | 3623 | |4;audio-oscillator;97;96;128;81;78;79;130;74;79;110 23:09:46 INFO - PROCESS | 3623 | |5;imaging-gaussian-blur;89;90;134;86;90;86;90;83;90;84 23:09:46 INFO - PROCESS | 3623 | |6;imaging-darkroom;94;94;95;94;94;94;95;95;95;94 23:09:46 INFO - PROCESS | 3623 | |7;imaging-desaturate;87;90;85;90;87;86;90;89;89;87 23:09:46 INFO - PROCESS | 3623 | |8;json-parse-financial;54;55;53;53;52;55;53;64;55;51 23:09:46 INFO - PROCESS | 3623 | |9;json-stringify-tinderbox;66;64;76;72;73;70;68;68;70;72 23:09:46 INFO - PROCESS | 3623 | |10;stanford-crypto-aes;83;80;82;81;82;83;82;83;85;82 23:09:46 INFO - PROCESS | 3623 | |11;stanford-crypto-ccm;132;131;131;128;120;121;124;146;130;138 23:09:46 INFO - PROCESS | 3623 | |12;stanford-crypto-pbkdf2;140;129;140;139;138;129;138;140;141;136 23:09:46 INFO - PROCESS | 3623 | |13;stanford-crypto-sha256-iterative;66;66;66;66;67;68;86;97;98;97 23:09:46 INFO - PROCESS | 3623 | __end_tp_report 23:09:46 INFO - PROCESS | 3623 | __start_cc_report 23:09:46 INFO - PROCESS | 3623 | _x_x_mozilla_cycle_collect,1223 23:09:46 INFO - PROCESS | 3623 | __end_cc_report 23:09:46 INFO - PROCESS | 3623 | __startTimestamp1456988986905__endTimestamp 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | ------- Summary: start ------- 23:09:46 INFO - PROCESS | 3623 | Number of tests: 14 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:1.40 (1.2%) stddev-sans-first:1.48 23:09:46 INFO - PROCESS | 3623 | Values: 111.0 110.0 112.0 112.0 112.0 113.0 113.0 110.0 110.0 109.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#1] audio-beat-detection Cycles:10 Average:152.90 Median:155.50 stddev:20.52 (13.2%) stddev-sans-first:21.61 23:09:46 INFO - PROCESS | 3623 | Values: 160.0 122.0 146.0 164.0 151.0 170.0 131.0 141.0 194.0 150.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#2] audio-dft Cycles:10 Average:203.20 Median:194.00 stddev:30.55 (15.7%) stddev-sans-first:32.37 23:09:46 INFO - PROCESS | 3623 | Values: 199.0 257.0 186.0 184.0 185.0 189.0 186.0 204.0 262.0 180.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#3] audio-fft Cycles:10 Average:70.60 Median:77.50 stddev:12.27 (15.8%) stddev-sans-first:12.72 23:09:46 INFO - PROCESS | 3623 | Values: 78.0 76.0 86.0 79.0 57.0 57.0 57.0 83.0 77.0 56.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:79.00 stddev:21.03 (26.6%) stddev-sans-first:22.30 23:09:46 INFO - PROCESS | 3623 | Values: 97.0 96.0 128.0 81.0 78.0 79.0 130.0 74.0 79.0 110.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:89.50 stddev:14.93 (16.7%) stddev-sans-first:15.79 23:09:46 INFO - PROCESS | 3623 | Values: 89.0 90.0 134.0 86.0 90.0 86.0 90.0 83.0 90.0 84.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 23:09:46 INFO - PROCESS | 3623 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#7] imaging-desaturate Cycles:10 Average:88.00 Median:89.00 stddev:1.83 (2.1%) stddev-sans-first:1.90 23:09:46 INFO - PROCESS | 3623 | Values: 87.0 90.0 85.0 90.0 87.0 86.0 90.0 89.0 89.0 87.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:3.60 (6.6%) stddev-sans-first:3.81 23:09:46 INFO - PROCESS | 3623 | Values: 54.0 55.0 53.0 53.0 52.0 55.0 53.0 64.0 55.0 51.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:71.00 stddev:3.54 (5.0%) stddev-sans-first:3.46 23:09:46 INFO - PROCESS | 3623 | Values: 66.0 64.0 76.0 72.0 73.0 70.0 68.0 68.0 70.0 72.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.50 stddev:1.34 (1.6%) stddev-sans-first:1.39 23:09:46 INFO - PROCESS | 3623 | Values: 83.0 80.0 82.0 81.0 82.0 83.0 82.0 83.0 85.0 82.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#11] stanford-crypto-ccm Cycles:10 Average:130.10 Median:131.00 stddev:7.80 (6.0%) stddev-sans-first:8.24 23:09:46 INFO - PROCESS | 3623 | Values: 132.0 131.0 131.0 128.0 120.0 121.0 124.0 146.0 130.0 138.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.00 Median:139.50 stddev:4.45 (3.2%) stddev-sans-first:4.58 23:09:46 INFO - PROCESS | 3623 | Values: 140.0 129.0 140.0 139.0 138.0 129.0 138.0 140.0 141.0 136.0 23:09:46 INFO - PROCESS | 3623 | 23:09:46 INFO - PROCESS | 3623 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.70 Median:77.00 stddev:14.84 (19.3%) stddev-sans-first:15.12 23:09:46 INFO - PROCESS | 3623 | Values: 66.0 66.0 66.0 66.0 67.0 68.0 86.0 97.0 98.0 97.0 23:09:46 INFO - PROCESS | 3623 | -------- Summary: end -------- 23:09:46 INFO - PROCESS | 3623 | 23:09:47 INFO - TEST-INFO | 3623: exit 0 23:09:47 INFO - TEST-OK | kraken | took 57357ms 23:09:47 INFO - SUITE-END | took 1171s 23:09:48 INFO - Completed test suite (00:19:31) 23:09:48 INFO - Working with test: dromaeo_css 23:09:48 INFO - Generating results file: dromaeo_css 23:09:48 INFO - Working with test: kraken 23:09:48 INFO - Generating results file: kraken 23:09:48 INFO - javascript benchmark 23:09:48 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:09:48 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 23:09:48 INFO - process_Request line: dromaeo_css 7364.29 graph.html#tests=[[72,64,59]] 23:09:48 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:09:48 INFO - process_Request line: kraken 1459.20 graph.html#tests=[[232,64,59]] 23:09:48 INFO - RETURN: dromaeo_css: 7364.29 23:09:48 INFO - RETURN: kraken: 1459.20 23:09:48 INFO - javascript benchmark 23:09:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3363.108776174314, 3397.2186397128658, 3436.9656303436964, 3444.3455743408736, 3481.442969124939, 5531.889362212763, 5616.382197958222, 5619.83140505785, 5630.352509461408, 5650.434956504353, 5666.235058267132, 5667.149927510875, 5700.7719691212405, 5807.070868661015, 5823.184754134414, 5097.898042039157, 5119.720069982504, 5130.486951304877, 5153.2785410042625, 5255.789768409259, 5195.636305458601, 5224.817131400402, 5283.841484755463, 5288.233206185101, 5320.574354051667, 5091.643584949055, 5106.693598384109, 5166.07010738067, 5207.427183009866, 5240.764165612557, 5136.04983078128, 5145.25393817898, 5182.896342073137, 5183.352080989876, 5244.527992480657, 5099.796008159669, 5147.433782283946, 5173.611979101583, 5182.2485739567555, 5189.948100518985, 4579.542045795427, 5475.151351540518, 5598.636088654224, 5602.747876345574, 5664.291963504562, 6146.446819786196, 6153.1693221415435, 6169.167162433059, 6181.289151747516, 6186.690665466709, 6204.7828326008375, 6222.937770622281, 6230.501559875199, 6235.7505699771955, 6262.373762623747, 5671.120976248647, 5694.971525142348, 5708.1723150143, 5718.828435146952, 5723.255976723041, 6261.530385221126, 6263.404976527223, 6263.498920086382, 6266.811995640138, 6271.843203919939, 4500.909981800346, 5845.707714614252, 5891.351951285441, 5969.701514924323, 5971.581989260797, 6060.757569697163, 6125.84685382869, 6130.29501607312, 6152.923238433345, 6160.075988601746, 6099.461111717566, 6120.66336351496, 6144.139820425144, 6160.44555989963, 6171.907421388682, 6132.436228761637, 6148.415900489446, 6149.7847575335545, 6157.476614487729, 6159.1069294951985, 4777.784999675023, 4851.830185943546, 4854.271859221145, 4854.878628034327, 4891.633127515451, 4349.521552629209, 4357.9564204358185, 4443.133588950123, 4450.087732014929, 4453.755043472575, 5502.119660854244, 5566.081596536607, 5578.2748242728185, 5589.0, 5593.049181639131, 5062.746862656926, 6118.596368888929, 6123.343628574293, 6131.040261761288, 6153.507719382439, 5549.0, 5604.271444712243, 5624.100143976944, 5660.830175094754, 5662.518685911661, 5294.814681486207, 5303.4696530346655, 5306.389765176977, 5311.28297679816, 5322.840314790485, 6258.530610204253, 6273.843153921189, 6291.905621415683, 6295.307516173218, 6300.054991751274, 6104.084387341934, 6162.7534898605345, 6234.407731265523, 6259.0, 6281.340459251713, 6243.0, 6272.5922815016875, 6283.5287353449585, 6288.811335659938, 6289.685515724287, 5952.910706339322, 6056.212692349966, 6064.181335519648, 6073.271207455134, 6084.6349219047, 6051.940910340761, 6129.48104857405, 6134.252905685562, 6158.969205154125, 6251.906221406592, 6016.368281330398, 6023.36754640774, 6050.848728781815, 6072.939270607414, 6098.390160984043, 5847.093700476433, 6003.279606447254, 6020.698965051817, 6038.335783063947, 6039.818805435844, 5754.309482862083, 5817.331006934257, 5881.17663527098, 5887.293524777054, 5919.052951527735, 6006.429389208019, 6030.728617212324, 6048.939510604838, 6062.969685151546, 6081.543884208613, 5750.712464376848, 5856.385079566755, 5886.882262354815, 5913.940860591339, 5941.643501389931], "unit": "score", "name": "dojo.html", "value": 5625.968546547653}, {"lowerIsBetter": false, "replicates": [485.4223474065859, 572.8857372410661, 574.7729646789518, 625.612120485299, 634.5780056262586, 37128.81435592825, 37688.62311376885, 37700.62299377002, 37715.43426848596, 37815.810920945354, 40056.0, 40742.79628601853, 40755.38866916999, 40755.796221018856, 40773.59226407735, 9409.717708468757, 9633.325667203299, 9650.420974741503, 9651.71044868655, 9662.227021838271, 9442.150206481416, 9587.424754514717, 9596.472194029326, 9605.519724013806, 9608.327417080807, 9614.13472787449, 9664.178544823699, 9679.27405444591, 9688.321817472779, 9744.707658770249, 9629.903700963006, 9715.27135464836, 9718.708438746813, 9729.221662267002, 9738.269629777724, 9575.99452057538, 9700.563474643657, 9716.805663886682, 9717.271204659679, 9727.610895564168, 38855.0, 39174.21651566953, 39212.80393598014, 39250.60749392498, 39251.82244532669, 2993.1918382036947, 3128.6871312868757, 3256.0971384163163, 3391.321735652855, 3717.7211709121652, 26646.467070658673, 26851.86574067137, 27554.311142221406, 27561.0, 27604.033858814844, 2660.162048954573, 2693.2189664997322, 2778.9440012795008, 2829.773618110567, 2830.6886242513306, 393943.0, 397888.0, 398375.0, 398528.0, 399190.0, 60344.0, 63499.0, 63531.72936541243, 63567.0, 63652.04521932175, 67543.32456675469, 69083.0, 69090.0, 69162.3083769156, 69176.96234556485, 16384.0, 16544.090075045988, 16578.917105414395, 16629.91905526137, 16661.91669041647, 12743.04427167966, 12777.977761779035, 12789.61631151067, 12819.743605128031, 12834.614961551168, 19215.231390744495, 20353.287634933004, 20363.490912727004, 20381.796182038288, 20398.79601203969, 8929.374943753875, 9091.909080909107, 9096.954515227382, 9123.726288211365, 9125.954370228106, 726.9709211631475, 927.4898805656861, 937.6015193542717, 1077.849101125843, 1122.910167186623, 23702.0, 28118.859405702842, 28368.744758176897, 28869.422611547652, 29034.854825725735, 823.3660081737029, 1076.3703233608426, 1108.5177947592822, 1112.3159257056852, 1119.9552017919193, 10423.895761042444, 14505.171491559257, 14546.4908728194, 14650.878396516275, 14781.556553303419, 509537.4523127361, 511265.0, 514057.0, 515390.0, 516328.0, 55542.16686749702, 56374.0, 56391.43608563862, 56403.71798140983, 56447.71776141093, 1255.8932490738207, 1391.8399384070758, 1413.6677880698028, 1426.2868565717142, 1542.2211783049488, 27640.723592764618, 27673.723262767115, 28295.434091317646, 28338.2915427116, 29330.266743331587, 45570.31644525401, 45726.31410528778, 46110.7694461539, 46133.30800037935, 46144.769276153405, 366246.0, 367595.0, 367743.0, 368819.0, 369836.0, 27704.168874933785, 31435.371292574477, 32603.510947335333, 32655.673443266212, 32676.83661581677, 39682.80158599189, 40283.59716402798, 40341.59658403378, 40632.30913779695, 41337.96655083647, 35057.82471087628, 35238.647613523535, 35931.28137437289, 36059.09852253715, 36188.0, 34550.96347109591, 34752.47871281984, 35013.649863502054, 35414.0, 35444.645553544135, 34507.654923451446, 34790.0, 35018.12454688653, 35150.12124696903, 35499.467507987916, 22406.32781016572, 30433.543496847124, 30963.342881279732, 30984.380312393176, 31045.68954310428, 23306.3008109757, 23674.171404000776, 23674.881625591763, 23818.88090559536, 23819.88090059608], "unit": "score", "name": "ext.html", "value": 19122.249944789375}, {"lowerIsBetter": false, "replicates": [347.35045464980504, 347.5950517646941, 348.53644652612013, 349.577011815703, 356.8269389346168, 15275.923620381911, 15647.374105035786, 19196.040197990096, 19223.32718354856, 20874.26940057098, 18859.811401885978, 18905.90547047263, 19325.613487730236, 19787.703184452246, 19835.504112397197, 5581.804636837691, 5586.86032849178, 5766.596338256324, 5799.536037117021, 5859.7656093756195, 3826.8469261229516, 5620.044592419298, 5714.742836572344, 5736.996025695486, 5767.134929760548, 5837.854053648672, 5838.9124163137585, 5840.415958404168, 5845.269341332333, 5850.88298234035, 5806.564507661942, 5809.970950145264, 5817.796377126801, 6285.0, 6356.96821515894, 5629.0, 5633.521150702195, 5637.154426835967, 5642.774289028475, 5646.447227012563, 12806.679833004157, 16979.660406791918, 21886.671699924515, 24762.628560571608, 24786.628200577004, 2053.3531937439657, 2085.5620319732875, 2106.9157233710635, 2149.613069647463, 2241.57410092082, 9066.456012639197, 9650.75873103171, 9716.90283097167, 9860.704178874645, 9926.602935882556, 1640.286475383212, 1718.785151856018, 1722.7243641017376, 1730.126286225448, 1791.6774980503503, 106718.46640766824, 109644.90355096427, 110351.0, 113892.72214555662, 114004.85995140026, 9973.700788976343, 10877.456127193609, 11032.889671103187, 11045.226834121695, 11082.889171108185, 12149.635510934686, 12509.49962001528, 13458.394372253271, 13588.93205533966, 13597.728045439037, 8258.0, 8270.627821748036, 8300.0, 8522.957385213034, 8525.74422767318, 8325.666973321, 8365.498070115813, 8512.106228846005, 8589.098144694843, 8608.0, 4338.0, 4428.22506061438, 4447.666425018137, 4474.485434175112, 4485.0805584855025, 1814.7640806695044, 1859.5072305838964, 1864.2356633780084, 1867.757191565115, 1871.1579789094963, 823.9134890836376, 834.3950635789125, 916.4684482999844, 991.4398364923795, 1008.0020779428313, 14835.0, 19227.0, 19436.319728809707, 19745.901270493556, 19809.0, 911.3119594705954, 912.9045145824904, 968.4770224079059, 971.6356366362614, 1020.882598501167, 10579.894201058045, 10804.891951080546, 10825.296355736851, 10832.079273261861, 10878.129749620011, 447957.0, 456531.0, 456951.0, 623118.0, 627866.0, 125587.70839882825, 126376.36811815882, 131687.0, 132446.33776831246, 132886.0, 1007.5617106558524, 1038.5170895533463, 1044.3733759744246, 1047.5024363427463, 1048.8567461467037, 14869.851301487279, 18096.72854907204, 18217.271309147487, 18349.0, 19008.42974710761, 39057.609423906535, 39568.80215598903, 50624.0, 50697.493025070755, 51595.484045159064, 66303.66848165728, 66886.99669505059, 71163.93254101089, 75163.0, 75283.62358188174, 7590.962045189739, 7824.295813376823, 7937.960310198412, 8053.1946805319, 8202.671893124209, 11357.602483913024, 12612.432440540015, 12941.0, 13137.0, 13156.013299002421, 10891.945540272249, 11057.83413248818, 11302.830457543309, 11433.428328583372, 11515.25150865191, 8942.50816205115, 10830.51262693165, 11382.203245772716, 11480.540778368771, 11892.76214475723, 11547.480363383836, 11556.248843825124, 11739.530418783495, 11924.940375298414, 11962.58130965412, 6877.899536074204, 6957.199922009034, 7860.960695196487, 7883.211678832071, 8344.666213351398, 11061.944690276498, 11379.658610241706, 11415.828762568734, 11464.140189485655, 11813.763724725286], "unit": "score", "name": "jquery.html", "value": 9166.908787149998}, {"lowerIsBetter": false, "replicates": [1417.1922004457463, 1523.4591719939422, 1550.7053659804633, 1765.788105427349, 1929.7877233504312, 7821.608919554028, 8437.240648341649, 8567.18611731886, 8604.225619694227, 8617.224449799516, 8323.87741126012, 8540.444871083375, 8557.486550806956, 8562.357823163256, 8567.742967710963, 2102.7581828089815, 2379.0602711928777, 2415.323709361377, 2440.6339049142593, 2443.58459061959, 2364.645303204516, 2371.976280237193, 2372.122314743547, 2373.2524254859663, 2374.620060790274, 2225.321277010511, 2243.809276211524, 2305.965410518844, 2306.8615883046987, 2312.0, 2336.3458231695154, 2337.0418128567208, 2353.5057637895893, 2448.277758061376, 2449.0, 2329.895154718042, 2359.2332491940188, 2361.2562042956415, 2363.5745565798147, 2455.6316552517087, 8609.69866054685, 8679.0, 8689.17452841979, 8696.739097827076, 8710.782230444227, 5281.683099014072, 5380.4350543192795, 5408.242846001563, 5419.810306639288, 5428.61999660024, 3528.858845646171, 3597.6042635310096, 3659.1218107654236, 3668.046307959923, 3676.117731744389, 1032.1587905856702, 1048.5334026358273, 1051.7318083888636, 1052.3948729480503, 1066.3441983180367, 11677.065834733201, 11679.591214307544, 11686.298822070703, 11695.35675537845, 11714.472848721827, 3834.789086600208, 4606.193916064743, 4724.149653062413, 4807.278908163734, 4850.320955066293, 10032.996700329908, 10037.64868229623, 10042.397456152654, 10047.698569042941, 10067.295289329677, 5311.893762124813, 5362.00802851466, 5373.65071270374, 5378.220158077135, 5383.650062745909, 4328.09110086879, 4510.909781804346, 4668.996165824364, 4775.6657034007985, 4778.904421911542, 3789.905252368713, 3877.9224415512094, 3887.6501114899793, 3920.2943470175073, 3954.5847685992558, 1251.217988757027, 1256.1018871506917, 1256.6292943581666, 1267.8541967673837, 1271.675722690708, 1100.8183649697871, 1110.611286049876, 1113.2207454781685, 1243.0, 1352.2900477249523, 7857.1357150713375, 7999.520028798291, 8045.477043992122, 8077.232662897015, 8081.353491720648, 482.09606986899564, 496.2704823908848, 498.9027139707722, 500.3945226276174, 502.1689104532005, 6105.938940610626, 6178.783742568989, 6183.103449999725, 6183.752649894044, 6198.907016394758, 10091.243156763272, 10213.182945364502, 10226.795464090677, 10312.968703129627, 10357.63748268807, 9271.629134834666, 10041.849372259421, 10176.440295783656, 10198.643047493451, 10199.28604997658, 6932.896006559906, 7940.245676660708, 8109.472884262504, 8149.877751833727, 8181.550014749127, 7727.0, 7830.373570114263, 7924.286814186751, 7927.682892684228, 8070.2333278338465, 8913.866292005496, 9256.028117047614, 9420.199283061016, 9522.23822094217, 9534.332596718163, 9326.347155699308, 9434.905650943403, 9575.94664586881, 9594.13652771226, 9744.951275243578, 6723.159605049369, 6927.7228910845015, 7162.427005839416, 7334.559926404433, 7481.738139165103, 8089.393295502979, 8127.431079824592, 8176.386770992318, 8180.018397792303, 8191.221833925768, 7094.4679149062995, 7164.928350716635, 7180.712771489291, 7307.744228951961, 7342.265773422615, 7046.964765176142, 7060.82347941285, 7165.749198778018, 7208.207097219197, 7224.349808517259, 7292.570755893313, 7513.2862378074, 7514.699412023677, 7558.13081495613, 7573.734919278019, 6855.417289530246, 6857.177138743383, 6889.793306200822, 6901.240863505111, 6909.1018167637885, 6868.347506986828, 6990.161180658353, 6992.685329160098, 6994.5803251805055, 7004.194517630538], "unit": "score", "name": "mootools.html", "value": 4712.534031248525}, {"lowerIsBetter": false, "replicates": [304.85824091797315, 309.13288226524605, 310.90672798160546, 312.2085513223977, 314.70103401768324, 6522.9021564676505, 6609.702563384647, 6729.865402691943, 6757.966210168942, 6759.256481787, 6580.045893345474, 6619.17260342456, 6671.432928201097, 6672.0, 6682.264950855414, 3723.609021052791, 4592.163134746095, 4742.881427964294, 4874.195757699975, 4875.463698993117, 3720.5163328767358, 4781.780645935281, 4796.808127674889, 4819.1325561398935, 4828.493008234138, 3915.7650540967493, 4677.830542364409, 4731.053789242148, 4740.0, 4752.07334569759, 3825.0, 4549.863504094882, 4627.1439783640135, 4749.073930583503, 4773.379460670126, 4037.7577345359077, 4040.4949381327333, 4047.1298670785923, 4047.947533641245, 4054.553999060101, 6318.810435686937, 6369.490440764774, 6378.87242255157, 6385.138006369174, 6402.423781859608, 5524.447555244484, 5527.53015993641, 5539.529140023103, 5540.722963851832, 5542.307211598551, 6239.7208572243035, 6965.686544105527, 6978.162620485524, 6980.95285707148, 6986.40615547679, 4128.348660535809, 4261.786910654486, 4444.555544445562, 4453.732776033448, 4457.48738895028, 6656.93343066568, 6693.899591506131, 7206.639668016578, 7307.744228951961, 7424.703011879572, 3737.2338670572467, 4520.886977825515, 4528.818847246139, 4530.275155975094, 4532.524084971031, 4602.7468489233415, 4607.516210797885, 4615.2384856498975, 4624.398828152386, 4629.51390104041, 4791.448983366888, 5486.807961721321, 5492.286002819608, 5509.090999984957, 5514.503694667499, 5846.269216347957, 6602.801915942529, 7027.926761605237, 7036.6129862857015, 7044.189918159374, 1646.6542026174639, 1648.1100205888686, 1661.2773443552094, 1664.2427695398565, 1667.7748503952055, 1042.1766804224728, 1067.1836045425257, 1068.076114161245, 1084.2139448899484, 1099.6316234061678, 5229.973850130801, 6349.365063493614, 6465.127207826978, 6496.0, 6508.642024688687, 7371.557706537625, 7375.631218439056, 7376.409887209011, 7376.967224588562, 7379.048380647677, 6571.474282057424, 6584.539082264291, 6588.209414870246, 6595.439387652007, 6595.439387652007, 3311.9503207451908, 3392.9481860623287, 3841.5006049213425, 3886.805659717003, 3891.902702432405, 7347.191808901017, 7572.053493313336, 7591.127020392725, 7605.391568674493, 7665.118511371264, 7467.813304667427, 7497.362724168507, 7501.849963000818, 7517.661705223278, 7587.810304742426, 6172.938270617326, 6213.5339849510565, 7652.7704168875025, 7674.0, 7675.078990521061, 7342.926570734225, 7345.632718363953, 7350.411967042731, 7362.006129172707, 7362.153352364332, 6962.7911162665205, 7228.31331023552, 7248.130224373109, 7253.637318134178, 7263.455240856852, 7636.694532218871, 7642.694292228249, 7645.847083058419, 7653.9234607655435, 7654.2345765423015, 4519.97740011311, 4548.727076375428, 4566.70316429431, 4572.519885412117, 4573.199690054294, 7277.417806575355, 7335.77992660221, 7340.96329518349, 7351.081114860643, 7357.705691772269, 6767.627780471925, 7029.191642961125, 7036.824079398056, 7038.507304488637, 7054.611996340251, 6966.825829354307, 7011.544249623758, 7024.4029257513685, 7052.365287124184, 7085.574865508086, 6943.340382663639, 6950.930490695028, 6951.895721564079, 6965.442764578923, 6965.885458326644, 6829.146356705412, 7092.468064895051, 7104.218535960937, 7111.893321600077, 7146.32109949554, 6765.763198288037, 6835.179778426422, 6863.450923926173, 6949.305069493213, 6986.755463558751], "unit": "score", "name": "prototype.html", "value": 5062.8657306379}, {"lowerIsBetter": false, "replicates": [648.455297550058, 654.9345065493451, 655.790147152911, 677.840707433753, 682.1302838880424, 14519.564413067625, 14591.635209119777, 14822.777658335133, 14830.258487075656, 15170.54488365348, 13749.931250343734, 13772.449102035907, 13815.240161791098, 13816.309184540783, 13820.861791382084, 715.8389362393472, 724.6847621284732, 835.757630287216, 850.200811237438, 863.0894406401244, 867.2758246863876, 879.8592225243963, 880.106691707917, 882.3294296334767, 883.0771843423638, 910.5401772105077, 923.4782347973407, 923.9353245272833, 924.1036194890945, 924.3206243411088, 740.1007775552703, 891.4116682989261, 908.2688435809171, 917.4449458077834, 919.4850883505259, 669.1134247122582, 675.4866301610812, 679.7077256779583, 679.7383007542082, 680.2924958043628, 14397.280135993158, 14429.134251944917, 14459.0, 14464.13215207091, 14469.987100902941, 12292.877071229352, 12359.382030898509, 12404.689882752913, 12476.875231247663, 12650.367481625883, 9736.561854716554, 9936.25478089139, 9944.950275248577, 9974.750631234278, 10248.333858299187, 3423.3153369326233, 3454.9309013819834, 3485.825708714554, 3498.772579782306, 3575.284943011382, 79997.60001200016, 80033.99999999942, 80314.19685803125, 80349.59825200895, 80417.3916521672, 8931.687390941286, 9170.174684278447, 9194.862077068849, 9402.576884040234, 9516.571754271074, 17090.230939607747, 20284.79715202829, 20628.69056964147, 20651.69022464664, 20687.482812929797, 7247.673854676552, 7261.346478816826, 7304.890426643605, 7448.962755186298, 7502.587357695379, 3959.92080158401, 4018.819153138116, 4079.8572049978106, 4085.55058943516, 4146.792660366969, 2801.817881837674, 3250.9596928982974, 3470.878519251814, 3534.7702399344475, 3543.450765131409, 526.5471694342862, 610.4170517156106, 615.8944694273789, 629.6662768732579, 636.5257882877286, 538.4454012367268, 573.9081746920473, 577.8459407312076, 600.4445887554083, 608.5496732417979, 7983.560904150328, 8108.39187060961, 8216.876746848802, 8257.587120644064, 8291.461055031403, 493.2527221259794, 516.5273774496369, 559.7405836867049, 594.5013594993494, 601.4707057789118, 3611.6207798180812, 3800.619938006177, 3808.961910380861, 3875.496185495924, 3884.553276373253, 93731.53134234421, 97625.51187244018, 99752.0, 99916.50041749745, 101646.0, 80038.0, 82400.0, 82806.75789863156, 83185.58407207925, 83759.3248135046, 69947.30052699408, 70493.64753176304, 70655.29344706488, 70952.0, 71070.0, 7988.600569971362, 8332.333413326798, 8359.414840961075, 8541.231289184003, 8607.053224145213, 47308.0, 47653.761731192506, 48662.5133748658, 50423.74788126183, 51456.74271628768, 65933.0, 67456.6627166861, 69020.0, 70147.29852701408, 71290.64354678194, 10837.620683276364, 11135.0, 11219.831702524632, 11253.71865703364, 11528.308301501937, 11881.881181188422, 12011.699707507381, 12129.150959432754, 12296.016318694305, 12312.50749970027, 11309.830352544883, 11478.426078696199, 11574.18980671345, 11594.826077608674, 11619.037145527836, 11094.334339939629, 11331.546738130382, 11492.770144597229, 11638.825417618911, 11642.70893227676, 11045.226834121535, 11163.44182790841, 11181.440927953407, 11264.493097810455, 11274.323540587591, 9165.266778657558, 9261.16649501548, 9266.744947160774, 9322.394044387092, 9857.211423086279, 9161.0, 9374.25005999532, 9398.2951278653, 9874.62144457242, 10048.0], "unit": "score", "name": "yui.html", "value": 6778.605705162435}], "name": "dromaeo_css", "value": 7364.291779980728}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 110.0, 112.0, 112.0, 112.0, 113.0, 113.0, 110.0, 110.0, 109.0], "unit": "score", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [160.0, 122.0, 146.0, 164.0, 151.0, 170.0, 131.0, 141.0, 194.0, 150.0], "unit": "score", "name": "audio-beat-detection", "value": 152.9}, {"lowerIsBetter": true, "replicates": [199.0, 257.0, 186.0, 184.0, 185.0, 189.0, 186.0, 204.0, 262.0, 180.0], "unit": "score", "name": "audio-dft", "value": 203.2}, {"lowerIsBetter": true, "replicates": [78.0, 76.0, 86.0, 79.0, 57.0, 57.0, 57.0, 83.0, 77.0, 56.0], "unit": "score", "name": "audio-fft", "value": 70.6}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 128.0, 81.0, 78.0, 79.0, 130.0, 74.0, 79.0, 110.0], "unit": "score", "name": "audio-oscillator", "value": 95.2}, {"lowerIsBetter": true, "replicates": [89.0, 90.0, 134.0, 86.0, 90.0, 86.0, 90.0, 83.0, 90.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 92.2}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 94.4}, {"lowerIsBetter": true, "replicates": [87.0, 90.0, 85.0, 90.0, 87.0, 86.0, 90.0, 89.0, 89.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 88.0}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 53.0, 53.0, 52.0, 55.0, 53.0, 64.0, 55.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 54.5}, {"lowerIsBetter": true, "replicates": [66.0, 64.0, 76.0, 72.0, 73.0, 70.0, 68.0, 68.0, 70.0, 72.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.9}, {"lowerIsBetter": true, "replicates": [83.0, 80.0, 82.0, 81.0, 82.0, 83.0, 82.0, 83.0, 85.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.3}, {"lowerIsBetter": true, "replicates": [132.0, 131.0, 131.0, 128.0, 120.0, 121.0, 124.0, 146.0, 130.0, 138.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 130.1}, {"lowerIsBetter": true, "replicates": [140.0, 129.0, 140.0, 139.0, 138.0, 129.0, 138.0, 140.0, 141.0, 136.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.0}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 66.0, 66.0, 67.0, 68.0, 86.0, 97.0, 98.0, 97.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 77.7}], "name": "kraken", "value": 1459.2}]} 23:09:48 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1459.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7364.29"}}} 23:09:48 INFO - Return code: 0 23:09:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:09:48 INFO - # TBPL SUCCESS # 23:09:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:09:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:09:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:09:48 INFO - 2016-03-02 23:09:48.596 screenresolution[3627:91918] starting screenresolution argv=screenresolution get 23:09:48 INFO - 2016-03-02 23:09:48.599 screenresolution[3627:91918] Display 0: 1600x1200x32@60 23:09:48 INFO - 2016-03-02 23:09:48.605 screenresolution[3628:91921] starting screenresolution argv=screenresolution list 23:09:48 INFO - Available Modes on Display 0 23:09:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:09:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:09:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:09:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:09:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:09:48 INFO - Intel Iris: 23:09:48 INFO - Chipset Model: Intel Iris 23:09:48 INFO - Type: GPU 23:09:48 INFO - Bus: Built-In 23:09:48 INFO - VRAM (Dynamic, Max): 1536 MB 23:09:48 INFO - Vendor: Intel (0x8086) 23:09:48 INFO - Device ID: 0x0a2e 23:09:48 INFO - Revision ID: 0x0009 23:09:48 INFO - Displays: 23:09:48 INFO - Display: 23:09:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:09:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:09:48 INFO - Display Serial Number: 1600x1200 60 23:09:48 INFO - Main Display: Yes 23:09:48 INFO - Mirror: Off 23:09:48 INFO - Online: Yes 23:09:48 INFO - Rotation: Supported 23:09:48 INFO - Return code: 0 23:09:48 INFO - Running post-action listener: _resource_record_post_action 23:09:48 INFO - Running post-run listener: _resource_record_post_run 23:09:49 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 0; Write bytes: 701155328; Read time: 0; Write time: 1942 23:09:49 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392305152; Write bytes: 386946560; Read time: 29629; Write time: 811 23:09:49 INFO - run-tests - Wall time: 1175s; CPU: 52.0%; Read bytes: 0; Write bytes: 296325632; Read time: 0; Write time: 1090 23:09:49 INFO - Running post-run listener: _upload_blobber_files 23:09:49 INFO - Blob upload gear active. 23:09:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:09:49 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 23:09:49 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'] 23:09:49 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 23:09:50 INFO - (blobuploader) - INFO - Open directory for files ... 23:09:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:09:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:09:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:51 INFO - (blobuploader) - INFO - Done attempting. 23:09:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:09:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:09:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:09:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:09:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:09:52 INFO - (blobuploader) - INFO - Done attempting. 23:09:52 INFO - (blobuploader) - INFO - Iteration through files over. 23:09:52 INFO - Return code: 0 23:09:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:09:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:09:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec1e73ff67c69f0cb62c7e01124d0c85fcd87fff696264eb482fb9eb3492f66d0b5ade9fb445c4f65b4010276324ef94fb8c725b21278f018d5615e16c283885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5fc65c561d0ead8b35ec73f633bd7c8171c8bd3bc2fff6645568216f745535e92ef63e0d19b41a6ea781c3e881a50436db4966a7e1fcb4eaf97db891b7f27ad3"} 23:09:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:09:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:09:52 INFO - Contents: 23:09:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec1e73ff67c69f0cb62c7e01124d0c85fcd87fff696264eb482fb9eb3492f66d0b5ade9fb445c4f65b4010276324ef94fb8c725b21278f018d5615e16c283885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5fc65c561d0ead8b35ec73f633bd7c8171c8bd3bc2fff6645568216f745535e92ef63e0d19b41a6ea781c3e881a50436db4966a7e1fcb4eaf97db891b7f27ad3"} 23:09:52 INFO - Running post-run listener: copy_logs_to_upload_dir 23:09:52 INFO - Copying logs to upload dir... 23:09:52 INFO - mkdir: /builds/slave/test/build/upload/logs 23:09:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.650735 ========= master_lag: 3.46 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-03-02 23:09:56.032117) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-02 23:09:56.035209) ========= 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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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/ec1e73ff67c69f0cb62c7e01124d0c85fcd87fff696264eb482fb9eb3492f66d0b5ade9fb445c4f65b4010276324ef94fb8c725b21278f018d5615e16c283885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5fc65c561d0ead8b35ec73f633bd7c8171c8bd3bc2fff6645568216f745535e92ef63e0d19b41a6ea781c3e881a50436db4966a7e1fcb4eaf97db891b7f27ad3"} build_url:https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011386 build_url: 'https://queue.taskcluster.net/v1/task/UCPyjCo7QXSntdDil5XUSw/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec1e73ff67c69f0cb62c7e01124d0c85fcd87fff696264eb482fb9eb3492f66d0b5ade9fb445c4f65b4010276324ef94fb8c725b21278f018d5615e16c283885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5fc65c561d0ead8b35ec73f633bd7c8171c8bd3bc2fff6645568216f745535e92ef63e0d19b41a6ea781c3e881a50436db4966a7e1fcb4eaf97db891b7f27ad3"}' ========= master_lag: 0.14 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-02 23:09:56.190709) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 23:09:56.191037) ========= 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.wfkNGb6YXJ/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.19LWI3E5Ov/Listeners TMPDIR=/var/folders/yj/320nlnz57612b6dr2b3zmv8c00000w/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.005342 ========= master_lag: 0.78 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 23:09:56.979510) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-02 23:09:56.979866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-02 23:09:56.980874) ========= ========= Total master_lag: 4.56 =========