builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0189 starttime: 1458690155.88 results: success (0) buildid: 20160322142137 builduid: 8a20a358f34f4ce4bd854cc18eaaa289 revision: 1f504df693e94a79b8929e6d91fc5469f2356e43 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.882616) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.883032) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.883315) ========= 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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners TMPDIR=/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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.005979 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.913841) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.914130) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.935957) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.936262) ========= 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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners TMPDIR=/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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.005583 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.985922) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.986235) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.989680) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:35.990019) ========= 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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners TMPDIR=/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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-22 16:42:36-- 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% 9.17M=0.001s 2016-03-22 16:42:36 (9.17 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109338 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:36.116468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:36.116790) ========= 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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners TMPDIR=/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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.027500 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:36.165533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:36.168439) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1f504df693e94a79b8929e6d91fc5469f2356e43 --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 1f504df693e94a79b8929e6d91fc5469f2356e43 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8rVLKte3Fe/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.KcDLZwvkzp/Listeners TMPDIR=/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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-22 16:42:36,244 truncating revision to first 12 chars 2016-03-22 16:42:36,244 Setting DEBUG logging. 2016-03-22 16:42:36,244 attempt 1/10 2016-03-22 16:42:36,244 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1f504df693e9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-22 16:42:36,540 unpacking tar archive at: fx-team-1f504df693e9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.532629 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:36.718950) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:36.719391) ========= echo 1f504df693e94a79b8929e6d91fc5469f2356e43 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'1f504df693e94a79b8929e6d91fc5469f2356e43'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8rVLKte3Fe/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.KcDLZwvkzp/Listeners TMPDIR=/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 1f504df693e94a79b8929e6d91fc5469f2356e43 program finished with exit code 0 elapsedTime=0.005198 script_repo_revision: '1f504df693e94a79b8929e6d91fc5469f2356e43' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:36.768939) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:36.769282) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-22 16:42:36.787151) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2016-03-22 16:42:36.787470) ========= /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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners TMPDIR=/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:42:36 INFO - MultiFileLogger online at 20160322 16:42:36 in /builds/slave/test 16:42:36 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 16:42:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:42:36 INFO - {'append_to_log': False, 16:42:36 INFO - 'base_work_dir': '/builds/slave/test', 16:42:36 INFO - 'blob_upload_branch': 'Fx-Team', 16:42:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:42:36 INFO - 'branch': 'Fx-Team', 16:42:36 INFO - 'buildbot_json_path': 'buildprops.json', 16:42:36 INFO - 'config_files': ('talos/mac_config.py',), 16:42:36 INFO - 'default_actions': ('clobber', 16:42:36 INFO - 'read-buildbot-config', 16:42:36 INFO - 'download-and-extract', 16:42:36 INFO - 'populate-webroot', 16:42:36 INFO - 'create-virtualenv', 16:42:36 INFO - 'install', 16:42:36 INFO - 'run-tests'), 16:42:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:42:36 INFO - 'download_minidump_stackwalk': True, 16:42:36 INFO - 'download_symbols': 'ondemand', 16:42:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:42:36 INFO - 'tooltool.py': '/tools/tooltool.py', 16:42:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:42:36 INFO - '/tools/misc-python/virtualenv.py')}, 16:42:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:42:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:42:36 INFO - 'installer_path': 'installer.exe', 16:42:36 INFO - 'log_level': 'info', 16:42:36 INFO - 'log_name': 'talos', 16:42:36 INFO - 'log_to_console': True, 16:42:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:42:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:42:36 INFO - 'opt_config_files': (), 16:42:36 INFO - 'pip_index': False, 16:42:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:36 INFO - 'cmd': ('bash', 16:42:36 INFO - '-c', 16:42:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:36 INFO - 'enabled': True, 16:42:36 INFO - 'halt_on_failure': False, 16:42:36 INFO - 'name': 'check_screen_resolution'},), 16:42:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:36 INFO - 'cmd': ('bash', 16:42:36 INFO - '-c', 16:42:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:36 INFO - 'enabled': True, 16:42:36 INFO - 'halt_on_failure': False, 16:42:36 INFO - 'name': 'check_screen_resolution'},), 16:42:36 INFO - 'run_cmd_checks_enabled': True, 16:42:36 INFO - 'sps_profile': False, 16:42:36 INFO - 'sps_profile_interval': 0, 16:42:36 INFO - 'suite': 'dromaeojs', 16:42:36 INFO - 'system_bits': '32', 16:42:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:42:36 INFO - 'title': 't-yosemite-r7-0189', 16:42:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:42:36 INFO - 'use_talos_json': True, 16:42:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:42:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:42:36 INFO - 'work_dir': 'build'} 16:42:36 INFO - ##### 16:42:36 INFO - ##### Running clobber step. 16:42:36 INFO - ##### 16:42:36 INFO - Running pre-action listener: _resource_record_pre_action 16:42:36 INFO - Running main action method: clobber 16:42:36 INFO - rmtree: /builds/slave/test/build 16:42:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:42:38 INFO - Running post-action listener: _resource_record_post_action 16:42:38 INFO - ##### 16:42:38 INFO - ##### Running read-buildbot-config step. 16:42:38 INFO - ##### 16:42:38 INFO - Running pre-action listener: _resource_record_pre_action 16:42:38 INFO - Running main action method: read_buildbot_config 16:42:38 INFO - Using buildbot properties: 16:42:38 INFO - { 16:42:38 INFO - "project": "", 16:42:38 INFO - "product": "firefox", 16:42:38 INFO - "script_repo_revision": "production", 16:42:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:42:38 INFO - "repository": "", 16:42:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:42:38 INFO - "buildid": "20160322142137", 16:42:38 INFO - "pgo_build": "False", 16:42:38 INFO - "basedir": "/builds/slave/test", 16:42:38 INFO - "buildnumber": 612, 16:42:38 INFO - "slavename": "t-yosemite-r7-0189", 16:42:38 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:42:38 INFO - "platform": "yosemite_r7", 16:42:38 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:42:38 INFO - "branch": "fx-team", 16:42:38 INFO - "repo_path": "integration/fx-team", 16:42:38 INFO - "revision": "1f504df693e94a79b8929e6d91fc5469f2356e43", 16:42:38 INFO - "stage_platform": "macosx64", 16:42:38 INFO - "builduid": "8a20a358f34f4ce4bd854cc18eaaa289", 16:42:38 INFO - "slavebuilddir": "test" 16:42:38 INFO - } 16:42:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 16:42:38 INFO - Running post-action listener: _resource_record_post_action 16:42:38 INFO - ##### 16:42:38 INFO - ##### Running download-and-extract step. 16:42:38 INFO - ##### 16:42:38 INFO - Running pre-action listener: _resource_record_pre_action 16:42:38 INFO - Running main action method: download_and_extract 16:42:38 INFO - mkdir: /builds/slave/test/build/tests 16:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:42:38 INFO - https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:42:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 16:42:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 16:42:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 16:42:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 16:42:39 INFO - Downloaded 1199 bytes. 16:42:39 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 16:42:39 INFO - Using the following test package requirements: 16:42:39 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 16:42:39 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:42:39 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 16:42:39 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:42:39 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 16:42:39 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:42:39 INFO - u'jsshell-mac.zip'], 16:42:39 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:42:39 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 16:42:39 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 16:42:39 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:42:39 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 16:42:39 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:42:39 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 16:42:39 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:42:39 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 16:42:39 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:42:39 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 16:42:39 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:42:39 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')]} 16:42:39 INFO - https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:42:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:42:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:42:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:42:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:42:40 INFO - Downloaded 17860633 bytes. 16:42:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:42:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:42:44 INFO - Return code: 0 16:42:44 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:42:44 INFO - https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:42:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:42:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:42:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:42:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:42:45 INFO - Downloaded 17860633 bytes. 16:42:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:42:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:42:49 INFO - Return code: 0 16:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:42:49 INFO - https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:42:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:42:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:42:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:42:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:42:51 INFO - Downloaded 10848120 bytes. 16:42:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:42:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:42:51 INFO - Return code: 0 16:42: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')]} 16:42:51 INFO - https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:42:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:42:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:42:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 16:42:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 16:42:56 INFO - Downloaded 111274329 bytes. 16:42:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:42:56 INFO - mkdir: /builds/slave/test/properties 16:42:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:42:56 INFO - Writing to file /builds/slave/test/properties/build_url 16:42:56 INFO - Contents: 16:42:56 INFO - build_url:https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:42:56 INFO - Running post-action listener: _resource_record_post_action 16:42:56 INFO - Running post-action listener: set_extra_try_arguments 16:42:56 INFO - ##### 16:42:56 INFO - ##### Running populate-webroot step. 16:42:56 INFO - ##### 16:42:56 INFO - Running pre-action listener: _resource_record_pre_action 16:42:56 INFO - Running main action method: populate_webroot 16:42:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:42:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:42:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:42:57 INFO - '--tppagecycles', 16:42:57 INFO - '7'], 16:42:57 INFO - 'tests': ['tsvgm']}}, 16:42:57 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:42:57 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:42:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:42:57 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:42:57 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:42:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:42:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:42:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:57 INFO - 'talos_options': ['--e10s'], 16:42:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:42:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:57 INFO - 'tests': ['damp', 'tps']}, 16:42:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:57 INFO - 'talos_options': ['--e10s'], 16:42:57 INFO - 'tests': ['damp', 'tps']}, 16:42:57 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:42:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:42:57 INFO - 'other': {'tests': ['a11yr', 16:42:57 INFO - 'ts_paint', 16:42:57 INFO - 'tpaint', 16:42:57 INFO - 'sessionrestore', 16:42:57 INFO - 'sessionrestore_no_auto_restore', 16:42:57 INFO - 'tabpaint']}, 16:42:57 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:42:57 INFO - 'tests': ['a11yr', 16:42:57 INFO - 'ts_paint', 16:42:57 INFO - 'tpaint', 16:42:57 INFO - 'sessionrestore', 16:42:57 INFO - 'sessionrestore_no_auto_restore', 16:42:57 INFO - 'tabpaint']}, 16:42:57 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:42:57 INFO - 'tests': ['a11yr', 16:42:57 INFO - 'ts_paint', 16:42:57 INFO - 'tpaint', 16:42:57 INFO - 'sessionrestore', 16:42:57 INFO - 'sessionrestore_no_auto_restore', 16:42:57 INFO - 'tabpaint']}, 16:42:57 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:42:57 INFO - 'tests': ['a11yr', 16:42:57 INFO - 'ts_paint', 16:42:57 INFO - 'tpaint', 16:42:57 INFO - 'sessionrestore', 16:42:57 INFO - 'sessionrestore_no_auto_restore', 16:42:57 INFO - 'tabpaint']}, 16:42:57 INFO - 'other_l64': {'tests': ['a11yr', 16:42:57 INFO - 'ts_paint', 16:42:57 INFO - 'tpaint', 16:42:57 INFO - 'sessionrestore', 16:42:57 INFO - 'sessionrestore_no_auto_restore', 16:42:57 INFO - 'tabpaint']}, 16:42:57 INFO - 'other_nol64': {'tests': ['a11yr', 16:42:57 INFO - 'ts_paint', 16:42:57 INFO - 'tpaint', 16:42:57 INFO - 'sessionrestore', 16:42:57 INFO - 'sessionrestore_no_auto_restore', 16:42:57 INFO - 'tabpaint']}, 16:42:57 INFO - 'svgr': {'tests': ['tsvgx', 16:42:57 INFO - 'tsvgr_opacity', 16:42:57 INFO - 'tart', 16:42:57 INFO - 'tscrollx', 16:42:57 INFO - 'cart']}, 16:42:57 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:42:57 INFO - 'tests': ['tsvgx', 16:42:57 INFO - 'tsvgr_opacity', 16:42:57 INFO - 'tart', 16:42:57 INFO - 'tscrollx', 16:42:57 INFO - 'cart']}, 16:42:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:57 INFO - 'tests': ['tp5o']}, 16:42:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:57 INFO - 'talos_options': ['--e10s'], 16:42:57 INFO - 'tests': ['tp5o']}, 16:42:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:57 INFO - 'talos_options': ['--xperf_path', 16:42:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:42:57 INFO - 'tests': ['tp5n']}, 16:42:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:57 INFO - 'talos_options': ['--e10s', 16:42:57 INFO - '--xperf_path', 16:42:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:42:57 INFO - 'tests': ['tp5n']}}, 16:42:57 INFO - 'talos.zip': {'path': '', 16:42:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:42:57 INFO - Running post-action listener: _resource_record_post_action 16:42:57 INFO - ##### 16:42:57 INFO - ##### Running create-virtualenv step. 16:42:57 INFO - ##### 16:42:57 INFO - Running pre-action listener: _resource_record_pre_action 16:42:57 INFO - Running main action method: create_virtualenv 16:42:57 INFO - Creating virtualenv /builds/slave/test/build/venv 16:42:57 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 16:42:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:42:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:42:57 INFO - Using real prefix '/tools/python27' 16:42:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:42:57 INFO - Installing distribute.............................................................................................................................................................................................done. 16:43:00 INFO - Installing pip.................done. 16:43:00 INFO - Return code: 0 16:43:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43: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')]} 16:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:00 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10827c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da56f900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, '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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:43:00 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 16:43:00 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 16:43:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:43:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:00 INFO - 'HOME': '/Users/cltbld', 16:43:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:00 INFO - 'LOGNAME': 'cltbld', 16:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:00 INFO - 'MOZ_NO_REMOTE': '1', 16:43:00 INFO - 'NO_EM_RESTART': '1', 16:43:00 INFO - 'PAGER': '/bin/cat', 16:43:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:00 INFO - 'PWD': '/builds/slave/test', 16:43:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:00 INFO - 'SHELL': '/bin/bash', 16:43:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:43:00 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:43:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:00 INFO - 'USER': 'cltbld', 16:43:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:00 INFO - 'XPC_FLAGS': '0x0', 16:43:00 INFO - 'XPC_SERVICE_NAME': '0', 16:43:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:01 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 16:43:01 INFO - Cleaning up... 16:43:01 INFO - Return code: 0 16:43:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:43:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:01 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10827c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da56f900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, '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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:43:01 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 16:43:01 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 16:43:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:43:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:01 INFO - 'HOME': '/Users/cltbld', 16:43:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:01 INFO - 'LOGNAME': 'cltbld', 16:43:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:01 INFO - 'MOZ_NO_REMOTE': '1', 16:43:01 INFO - 'NO_EM_RESTART': '1', 16:43:01 INFO - 'PAGER': '/bin/cat', 16:43:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:01 INFO - 'PWD': '/builds/slave/test', 16:43:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:01 INFO - 'SHELL': '/bin/bash', 16:43:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:43:01 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:43:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:01 INFO - 'USER': 'cltbld', 16:43:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:01 INFO - 'XPC_FLAGS': '0x0', 16:43:01 INFO - 'XPC_SERVICE_NAME': '0', 16:43:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:01 INFO - Downloading/unpacking psutil>=0.7.1 16:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:43:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:43:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:43:04 INFO - Installing collected packages: psutil 16:43:04 INFO - Running setup.py install for psutil 16:43:04 INFO - building 'psutil._psutil_osx' extension 16:43:04 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 16:43:04 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 16:43:04 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 16:43:05 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 16:43:05 INFO - building 'psutil._psutil_posix' extension 16:43:05 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 16:43:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:43:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:43:05 INFO - ^ 16:43:05 INFO - 1 warning generated. 16:43:05 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 16:43:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:43:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:43:05 INFO - Successfully installed psutil 16:43:05 INFO - Cleaning up... 16:43:05 INFO - Return code: 0 16:43:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10827c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da56f900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, '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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:43:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:43:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:43:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:43:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:05 INFO - 'HOME': '/Users/cltbld', 16:43:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:05 INFO - 'LOGNAME': 'cltbld', 16:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:05 INFO - 'MOZ_NO_REMOTE': '1', 16:43:05 INFO - 'NO_EM_RESTART': '1', 16:43:05 INFO - 'PAGER': '/bin/cat', 16:43:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:05 INFO - 'PWD': '/builds/slave/test', 16:43:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:05 INFO - 'SHELL': '/bin/bash', 16:43:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:43:05 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:43:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:05 INFO - 'USER': 'cltbld', 16:43:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:05 INFO - 'XPC_FLAGS': '0x0', 16:43:05 INFO - 'XPC_SERVICE_NAME': '0', 16:43:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:43:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:43:08 INFO - Installing collected packages: mozsystemmonitor 16:43:08 INFO - Running setup.py install for mozsystemmonitor 16:43:08 INFO - Successfully installed mozsystemmonitor 16:43:08 INFO - Cleaning up... 16:43:08 INFO - Return code: 0 16:43:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:43:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:08 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10827c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da56f900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, '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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:43:08 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 16:43:08 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 16:43:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:43:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:08 INFO - 'HOME': '/Users/cltbld', 16:43:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:08 INFO - 'LOGNAME': 'cltbld', 16:43:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:08 INFO - 'MOZ_NO_REMOTE': '1', 16:43:08 INFO - 'NO_EM_RESTART': '1', 16:43:08 INFO - 'PAGER': '/bin/cat', 16:43:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:08 INFO - 'PWD': '/builds/slave/test', 16:43:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:08 INFO - 'SHELL': '/bin/bash', 16:43:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:43:08 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:43:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:08 INFO - 'USER': 'cltbld', 16:43:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:08 INFO - 'XPC_FLAGS': '0x0', 16:43:08 INFO - 'XPC_SERVICE_NAME': '0', 16:43:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:08 INFO - Downloading/unpacking blobuploader==1.2.4 16:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:10 INFO - Downloading blobuploader-1.2.4.tar.gz 16:43:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:43:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:43: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 16:43: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 16:43: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 16:43: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 16:43: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 16:43: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 16:43:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:43:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:43: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 16:43: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 16:43: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 16:43: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 16:43: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 16:43: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 16:43:12 INFO - Downloading docopt-0.6.1.tar.gz 16:43:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:43:12 INFO - Installing collected packages: blobuploader, requests, docopt 16:43:12 INFO - Running setup.py install for blobuploader 16:43:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:43:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:43:12 INFO - Running setup.py install for requests 16:43:12 INFO - Running setup.py install for docopt 16:43:13 INFO - Successfully installed blobuploader requests docopt 16:43:13 INFO - Cleaning up... 16:43:13 INFO - Return code: 0 16:43:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43:13 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')]} 16:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:13 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')]} 16:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:13 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10827c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da56f900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, '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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:43:13 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 16:43:13 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 16:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:13 INFO - 'HOME': '/Users/cltbld', 16:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:13 INFO - 'LOGNAME': 'cltbld', 16:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:13 INFO - 'MOZ_NO_REMOTE': '1', 16:43:13 INFO - 'NO_EM_RESTART': '1', 16:43:13 INFO - 'PAGER': '/bin/cat', 16:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:13 INFO - 'PWD': '/builds/slave/test', 16:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:13 INFO - 'SHELL': '/bin/bash', 16:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:43:13 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:13 INFO - 'USER': 'cltbld', 16:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:13 INFO - 'XPC_FLAGS': '0x0', 16:43:13 INFO - 'XPC_SERVICE_NAME': '0', 16:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:43:13 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-5kYg3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:43:13 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-hLnD12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:43:13 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-prWr4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:43:13 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-Jmu6ZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:43:13 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-aDtjpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:43:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:43:13 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-BA2TM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-uvWm4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-zK05p6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-lRIejP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-JCWiUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-kUQY4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-r_EtsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-iXjXME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-nUKVwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-SEZ7eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:43:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:43:14 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-pBKV8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:43:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:43:15 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-p7F7sJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:43:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:43:15 INFO - Running setup.py install for manifestparser 16:43:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:43:15 INFO - Running setup.py install for mozcrash 16:43:15 INFO - Running setup.py install for mozdebug 16:43:15 INFO - Running setup.py install for mozdevice 16:43:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:43:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:43:15 INFO - Running setup.py install for mozfile 16:43:15 INFO - Running setup.py install for mozhttpd 16:43:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:43:16 INFO - Running setup.py install for mozinfo 16:43:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:43:16 INFO - Running setup.py install for mozInstall 16:43:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:43:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:43:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:43:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:43:16 INFO - Running setup.py install for mozleak 16:43:16 INFO - Running setup.py install for mozlog 16:43:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:43:16 INFO - Running setup.py install for moznetwork 16:43:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:43:16 INFO - Running setup.py install for mozprocess 16:43:16 INFO - Running setup.py install for mozprofile 16:43:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:43:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:43:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:43:17 INFO - Running setup.py install for mozrunner 16:43:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:43:17 INFO - Running setup.py install for mozscreenshot 16:43:17 INFO - Running setup.py install for moztest 16:43:17 INFO - Running setup.py install for mozversion 16:43:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:43:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:43:17 INFO - Cleaning up... 16:43:17 INFO - Return code: 0 16:43:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43: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')]} 16:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:17 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10827c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da56f900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, '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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:43:17 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 16:43:17 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 16:43:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:43:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:17 INFO - 'HOME': '/Users/cltbld', 16:43:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:17 INFO - 'LOGNAME': 'cltbld', 16:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:17 INFO - 'MOZ_NO_REMOTE': '1', 16:43:17 INFO - 'NO_EM_RESTART': '1', 16:43:17 INFO - 'PAGER': '/bin/cat', 16:43:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:17 INFO - 'PWD': '/builds/slave/test', 16:43:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:17 INFO - 'SHELL': '/bin/bash', 16:43:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:43:17 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:43:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:17 INFO - 'USER': 'cltbld', 16:43:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:17 INFO - 'XPC_FLAGS': '0x0', 16:43:17 INFO - 'XPC_SERVICE_NAME': '0', 16:43:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:43:17 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-zXQh4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:43:18 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)) 16:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:43:18 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-K10yru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:43:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:43:18 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-TzYR6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:43:18 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)) 16:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:43:18 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-2JkCJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:43:18 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)) 16:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:43:18 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-ss6TzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:43:18 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)) 16:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:43:18 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-kpFyzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:43:18 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)) 16:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:43:18 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-UQE61w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:43:18 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)) 16:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:43:18 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-N5cQ1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:43:18 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)) 16:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:43:18 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-IejnXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:43:18 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)) 16:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:43:18 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-04HHDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:43:19 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)) 16:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:43:19 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-qHb7UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:43:19 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)) 16:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:43:19 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-cP7FHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:43:19 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)) 16:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:43:19 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-JCXfrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:43:19 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)) 16:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:43:19 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-vpFyMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:43:19 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)) 16:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:43:19 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-P9lU_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:43:19 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)) 16:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:43:19 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-oYsOGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:43:19 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)) 16:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:43:19 INFO - Running setup.py (path:/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/pip-C0Dqdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:43:19 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)) 16:43:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:43:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:43:19 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)) 16:43:19 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)) 16:43:19 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)) 16:43:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:43:19 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 16:43:19 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 16:43:19 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 16:43:19 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 16:43:19 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 16:43:19 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 16:43:22 INFO - Downloading blessings-1.6.tar.gz 16:43:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:43:22 INFO - Installing collected packages: blessings 16:43:22 INFO - Running setup.py install for blessings 16:43:22 INFO - Successfully installed blessings 16:43:22 INFO - Cleaning up... 16:43:22 INFO - Return code: 0 16:43:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:43:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:43:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:43:22 INFO - Reading from file tmpfile_stdout 16:43:22 INFO - Current package versions: 16:43:22 INFO - blessings == 1.6 16:43:22 INFO - blobuploader == 1.2.4 16:43:22 INFO - docopt == 0.6.1 16:43:22 INFO - manifestparser == 1.1 16:43:22 INFO - mozInstall == 1.12 16:43:22 INFO - mozcrash == 0.17 16:43:22 INFO - mozdebug == 0.1 16:43:22 INFO - mozdevice == 0.48 16:43:22 INFO - mozfile == 1.2 16:43:22 INFO - mozhttpd == 0.7 16:43:22 INFO - mozinfo == 0.9 16:43:22 INFO - mozleak == 0.1 16:43:22 INFO - mozlog == 3.1 16:43:22 INFO - moznetwork == 0.27 16:43:22 INFO - mozprocess == 0.22 16:43:22 INFO - mozprofile == 0.28 16:43:22 INFO - mozrunner == 6.11 16:43:22 INFO - mozscreenshot == 0.1 16:43:22 INFO - mozsystemmonitor == 0.0 16:43:22 INFO - moztest == 0.7 16:43:22 INFO - mozversion == 1.4 16:43:22 INFO - psutil == 3.1.1 16:43:22 INFO - requests == 1.2.3 16:43:22 INFO - wsgiref == 0.1.2 16:43:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43: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')]} 16:43:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:22 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10827c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da56f900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, '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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:43:22 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 16:43:22 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 16:43:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:43:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:22 INFO - 'HOME': '/Users/cltbld', 16:43:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:22 INFO - 'LOGNAME': 'cltbld', 16:43:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:22 INFO - 'MOZ_NO_REMOTE': '1', 16:43:22 INFO - 'NO_EM_RESTART': '1', 16:43:22 INFO - 'PAGER': '/bin/cat', 16:43:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:22 INFO - 'PWD': '/builds/slave/test', 16:43:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:22 INFO - 'SHELL': '/bin/bash', 16:43:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:43:22 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:43:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:22 INFO - 'USER': 'cltbld', 16:43:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:22 INFO - 'XPC_FLAGS': '0x0', 16:43:22 INFO - 'XPC_SERVICE_NAME': '0', 16:43:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:23 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)) 16:43:23 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)) 16:43:23 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)) 16:43:23 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)) 16:43:23 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)) 16:43:23 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)) 16:43:23 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)) 16:43:23 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)) 16:43:23 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)) 16:43:23 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)) 16:43:23 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)) 16:43:23 INFO - Cleaning up... 16:43:23 INFO - Return code: 0 16:43:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:43:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:23 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10827c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da56f900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, '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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:43:23 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 16:43:23 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 16:43:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:43:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:23 INFO - 'HOME': '/Users/cltbld', 16:43:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:23 INFO - 'LOGNAME': 'cltbld', 16:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:23 INFO - 'MOZ_NO_REMOTE': '1', 16:43:23 INFO - 'NO_EM_RESTART': '1', 16:43:23 INFO - 'PAGER': '/bin/cat', 16:43:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:23 INFO - 'PWD': '/builds/slave/test', 16:43:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:23 INFO - 'SHELL': '/bin/bash', 16:43:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:43:23 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:43:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:23 INFO - 'USER': 'cltbld', 16:43:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:23 INFO - 'XPC_FLAGS': '0x0', 16:43:23 INFO - 'XPC_SERVICE_NAME': '0', 16:43:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:23 INFO - Downloading/unpacking jsonschema 16:43:23 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 16:43:23 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 16:43:23 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 16:43:23 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 16:43:23 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 16:43:23 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 16:43:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:43:25 INFO - Downloading/unpacking functools32 (from jsonschema) 16:43:25 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 16:43:25 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 16:43:25 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 16:43:25 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 16:43:25 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 16:43:25 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 16:43:26 INFO - Downloading functools32-3.2.3-2.tar.gz 16:43:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:43:26 INFO - warning: no files found matching '*.txt' 16:43:26 INFO - no previously-included directories found matching 'build' 16:43:26 INFO - no previously-included directories found matching 'dist' 16:43:26 INFO - no previously-included directories found matching '.git*' 16:43:26 INFO - Installing collected packages: jsonschema, functools32 16:43:26 INFO - Running setup.py install for functools32 16:43:26 INFO - warning: no files found matching '*.txt' 16:43:26 INFO - no previously-included directories found matching 'build' 16:43:26 INFO - no previously-included directories found matching 'dist' 16:43:26 INFO - no previously-included directories found matching '.git*' 16:43:26 INFO - Successfully installed jsonschema functools32 16:43:26 INFO - Cleaning up... 16:43:26 INFO - Return code: 0 16:43:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43: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')]} 16:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:26 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10827c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9da56f900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, '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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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 16:43:26 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 16:43:26 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 16:43:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:43:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:26 INFO - 'HOME': '/Users/cltbld', 16:43:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:26 INFO - 'LOGNAME': 'cltbld', 16:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:26 INFO - 'MOZ_NO_REMOTE': '1', 16:43:26 INFO - 'NO_EM_RESTART': '1', 16:43:26 INFO - 'PAGER': '/bin/cat', 16:43:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:26 INFO - 'PWD': '/builds/slave/test', 16:43:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:26 INFO - 'SHELL': '/bin/bash', 16:43:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:43:26 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:43:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:26 INFO - 'USER': 'cltbld', 16:43:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:26 INFO - 'XPC_FLAGS': '0x0', 16:43:26 INFO - 'XPC_SERVICE_NAME': '0', 16:43:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:26 INFO - Downloading/unpacking flake8 16:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:43:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:43:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 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 16:43:29 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 16:43:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:43:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:43:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:43:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:43:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:43:30 INFO - no previously-included directories found matching 'docs/_build' 16:43:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:43:30 INFO - Running setup.py install for pep8 16:43:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:43:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:43:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:43:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:43:30 INFO - no previously-included directories found matching 'docs/_build' 16:43:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:43:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:43:30 INFO - Cleaning up... 16:43:30 INFO - Return code: 0 16:43:30 INFO - Running post-action listener: _resource_record_post_action 16:43:30 INFO - Running post-action listener: _start_resource_monitoring 16:43:30 INFO - Starting resource monitoring. 16:43:30 INFO - ##### 16:43:30 INFO - ##### Running install step. 16:43:30 INFO - ##### 16:43:30 INFO - Running pre-action listener: _resource_record_pre_action 16:43:30 INFO - Running main action method: install 16:43:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:43:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:43:31 INFO - Reading from file tmpfile_stdout 16:43:31 INFO - Detecting whether we're running mozinstall >=1.0... 16:43:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:43:31 INFO - Reading from file tmpfile_stdout 16:43:31 INFO - Output received: 16:43:31 INFO - Usage: mozinstall [options] installer 16:43:31 INFO - Options: 16:43:31 INFO - -h, --help show this help message and exit 16:43:31 INFO - -d DEST, --destination=DEST 16:43:31 INFO - Directory to install application into. [default: 16:43:31 INFO - "/builds/slave/test"] 16:43:31 INFO - --app=APP Application being installed. [default: firefox] 16:43:31 INFO - mkdir: /builds/slave/test/build/application 16:43:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:44:05 INFO - Reading from file tmpfile_stdout 16:44:05 INFO - Output received: 16:44:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:44:05 INFO - Running post-action listener: _resource_record_post_action 16:44:05 INFO - ##### 16:44:05 INFO - ##### Running run-tests step. 16:44:05 INFO - ##### 16:44:05 INFO - Running pre-action listener: _resource_record_pre_action 16:44:05 INFO - Running main action method: run_tests 16:44:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:44:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:44:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:44:05 INFO - 2016-03-22 16:44:05.521 screenresolution[1646:7399] starting screenresolution argv=screenresolution get 16:44:05 INFO - 2016-03-22 16:44:05.523 screenresolution[1646:7399] Display 0: 1600x1200x32@60 16:44:05 INFO - 2016-03-22 16:44:05.529 screenresolution[1647:7401] starting screenresolution argv=screenresolution list 16:44:05 INFO - Available Modes on Display 0 16:44:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:44:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:44:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:44:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:44:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:44:05 INFO - Intel Iris: 16:44:05 INFO - Chipset Model: Intel Iris 16:44:05 INFO - Type: GPU 16:44:05 INFO - Bus: Built-In 16:44:05 INFO - VRAM (Dynamic, Max): 1536 MB 16:44:05 INFO - Vendor: Intel (0x8086) 16:44:05 INFO - Device ID: 0x0a2e 16:44:05 INFO - Revision ID: 0x0009 16:44:05 INFO - Displays: 16:44:05 INFO - Display: 16:44:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:44:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:44:05 INFO - Display Serial Number: 1600x1200 60 16:44:05 INFO - Main Display: Yes 16:44:05 INFO - Mirror: Off 16:44:05 INFO - Online: Yes 16:44:05 INFO - Rotation: Supported 16:44:05 INFO - Return code: 0 16:44:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:44:05 INFO - Python 2.7.3 16:44:05 INFO - Return code: 0 16:44:05 INFO - grabbing minidump binary from tooltool 16:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:05 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 0x10810be00>, '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 0x107f34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108106b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:44:05 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 16:44:05 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 16:44:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:44:05 INFO - Return code: 0 16:44:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:44:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:44:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:44:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:44:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:44:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:44:07 INFO - Return code: 0 16:44:07 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/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 16:44:07 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/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0189 --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 16:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8rVLKte3Fe/Render', 16:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:07 INFO - 'HOME': '/Users/cltbld', 16:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:07 INFO - 'LOGNAME': 'cltbld', 16:44:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:44:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:07 INFO - 'MOZ_NO_REMOTE': '1', 16:44:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:44:07 INFO - 'NO_EM_RESTART': '1', 16:44:07 INFO - 'PAGER': '/bin/cat', 16:44:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:07 INFO - 'PWD': '/builds/slave/test', 16:44:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:07 INFO - 'SHELL': '/bin/bash', 16:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KcDLZwvkzp/Listeners', 16:44:07 INFO - 'TMPDIR': '/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/', 16:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:07 INFO - 'USER': 'cltbld', 16:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:07 INFO - 'XPC_FLAGS': '0x0', 16:44:07 INFO - 'XPC_SERVICE_NAME': '0', 16:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:07 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/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 16:44:07 INFO - mozversion application_buildid: 20160322142137 16:44:07 INFO - mozversion application_changeset: 1f504df693e94a79b8929e6d91fc5469f2356e43 16:44:07 INFO - mozversion application_display_name: Nightly 16:44:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:44:07 INFO - mozversion application_name: Firefox 16:44:07 INFO - mozversion application_remotingname: firefox 16:44:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:44:07 INFO - mozversion application_vendor: Mozilla 16:44:07 INFO - mozversion application_version: 48.0a1 16:44:07 INFO - mozversion platform_buildid: 20160322142137 16:44:07 INFO - mozversion platform_changeset: 1f504df693e94a79b8929e6d91fc5469f2356e43 16:44:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:44:07 INFO - mozversion platform_version: 48.0a1 16:44:07 INFO - using testdate: 1458690247 16:44:07 INFO - actual date: 1458690247 16:44:07 INFO - starting webserver on 'localhost:49272' 16:44:07 INFO - SUITE-START | Running 2 tests 16:44:07 INFO - TEST-START | dromaeo_css 16:44:07 INFO - operating with platform_type : mac_ 16:44:07 INFO - Initialising browser for dromaeo_css test... 16:44:07 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/tmpmA9ZrP/profile http://localhost:49272/getInfo.html) 16:44:09 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 16:44:09 INFO - PROCESS | 1665 | colorDepth:24 16:44:09 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 16:44:09 INFO - PROCESS | 1665 | __metrics 16:44:09 INFO - PROCESS | 1665 | 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" 16:44:09 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:44:10 INFO - TEST-INFO | 1665: exit 0 16:44:10 INFO - Browser initialized. 16:44:10 INFO - Running cycle 1/1 for dromaeo_css test... 16:44:10 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/tmpmA9ZrP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:44:22 INFO - PROCESS | 1668 | RSS: Main: 247730176 16:44:22 INFO - PROCESS | 1668 | 16:45:13 INFO - PROCESS | 1668 | 1458690313256 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] 16:47:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 16:47:25 INFO - PROCESS | 1668 | RSS: Main: 357269504 16:47:25 INFO - PROCESS | 1668 | 16:50:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 16:50:28 INFO - PROCESS | 1668 | RSS: Main: 380350464 16:50:28 INFO - PROCESS | 1668 | 16:53:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 16:53:30 INFO - PROCESS | 1668 | RSS: Main: 380669952 16:53:30 INFO - PROCESS | 1668 | 16:56:32 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 16:56:33 INFO - PROCESS | 1668 | RSS: Main: 335159296 16:56:33 INFO - PROCESS | 1668 | 16:59:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 16:59:37 INFO - PROCESS | 1668 | RSS: Main: 337342464 16:59:37 INFO - PROCESS | 1668 | 17:02:39 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 17:02:40 INFO - PROCESS | 1668 | RSS: Main: 385269760 17:02:40 INFO - PROCESS | 1668 | 17:02:40 INFO - PROCESS | 1668 | __start_tp_report 17:02:40 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 17:02:40 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 17:02:40 INFO - PROCESS | 1668 | |i|pagename|runs| 17:02:40 INFO - PROCESS | 1668 | |0;dojo.html;2862.227198656363;3273.4598791199446;3455.378031954247;3470.3926812807786;3492.4062909305417;4778.569928706415;5326.627136100475;5435.456454354572;5519.061759500884;5526.944730552693;5513.807016754415;5530.142827861688;5580.330360356753;5614.691191984439;5628.859278518029;4955.801767929279;4956.851294461172;4974.602031837444;4995.425526064513;5166.9224961625605;5090.847274581768;5101.132807422747;5111.846644600668;5117.974410127944;5145.048166089286;4953.653244272902;4959.107360675077;5048.444671086196;5054.3176671149295;5062.139436295838;5039.823606173803;5044.621653376012;5060.544550990392;5070.569001634829;5093.516115968977;4984.5264699853815;4991;5007.724575148365;5031.421386540558;5035.748212589356;5262;5346.625736198468;5360.758765855546;5391.32608423947;5474.452554744534;6080.939609360194;6092.482139018144;6097.93286174922;6098.3596722343955;6116.96941515294;6067.605605635619;6147.631142131487;6166.290876549209;6166.321704612489;6174.351693072253;5477.315335583052;5558.388577256498;5644.379118296984;5666.320041594994;5668.376478587352;6096.254562767794;6244;6254.593451425642;6256.593321434092;6259.655718935503;5866.090755932837;5878.470937615635;5885.823425297162;5926;5970.9701451492465;5953.672548009815;6077.149199112039;6105.389461053948;6105.542084343692;6137.662428566472;5970.492508136857;6038.486728628116;6038.607490513103;6057.787977420858;6093.329733729286;6033.125196846433;6034.0043892758085;6037;6045.21412216409;6064.818055458343;4606.401167848158;4762.547557981986;4773.0453909218295;4776.47458779534;4782.282657601318;4264.829406823755;4295.076558539929;4318.050028993616;4354.608085272341;4394.8242070317465;5416.187571864252;5446.482584154499;5487.451254874481;5499.285092937973;5539.19681646159;5918.615290006136;5950.880982380415;6115.296740874856;6123.387661233841;6136.079588061826;5410.567154627699;5472.124460086367;5477;5485.17722341644;5524.723763811793;5174.09453345663;5181.5077567631015;5194.35070616173;5198.688078715213;5259.973700131552;6121.84695382619;6177.073438984188;6294.937050629526;6312.400321969498;6312.652804095819;6087.208662873975;6139.338817383189;6163.7842675506145;6175.5677102602385;6175.907361389673;6270.467010304175;6292.056191571301;6292.528060395397;6294.213223347082;6306.621602703853;5849.210356601974;5960.850978725566;6008.699565021819;6021.849453763691;6065.090236464565;5980.282366116094;6059.454649081604;6069.544784141295;6114.938850611437;6137.662428566293;4929.088118698052;5531.806386776604;5679.943200567942;5851.678157701367;5863.384344643751;5681.971590142023;5817.970910145422;5824;5856.297244330707;5861.6189947654975;5621.128725047624;5721.313442386941;5770.798022069207;5800.274965629296;5817.447342502455;5838.824835254949;5868.794592189253;5870.26621672291;5870.823875283749;5937.168796368425;5577.581681373832;5670.574706896917;5677.744501497526;5738.598298119092;5740.1102829061565 17:02:40 INFO - PROCESS | 1668 | |1;ext.html;441.1926175099568;568.6531215958265;576.216345769753;629.9937000629996;641.5541198866788;29812.254693632643;40421;40955;40956;41005.38491922616;43437.56562434375;44104.77947610257;44122.7793861031;44139.67580972568;44238;7701.614919254043;8197.7130800422;9786.755331116708;9820.01799820016;9843.163331116864;9692.95153524235;9812.313138080337;9826.16477599405;9834.852477212848;9852;9779.902200978006;10001.59993600255;10006.59973601055;10014.248931330143;10022.899771002307;9871.950640246825;9894.604215831358;9902;9936.602535898519;9977.551010204557;8327.750167494924;9786.266030047775;9816.754581135528;9834.754131146707;9980.500974951223;40874.9781255471;40877.795611022055;40930;41358.793206034075;41798;3287.2439338952177;3418.4188687922865;3458.39478091333;3534.2224710563637;3810.771353718758;24248.757512424825;29221.41557168866;30138.397232055457;30558;30741;2680.6515153030177;2692.0846912049997;2735.7811375089946;2790.3861150546845;2875.8130721503035;359024;360478;360495;361296;361594.19202904083;63439;63915;64503;64523.67738161279;64875.35124648787;67923;67989.98015029778;68110.65944670344;68191.31808681949;68296.31703682999;17176.742348864776;17751.201195946214;17759.289628414976;17762.28950841952;17794.110294485483;13287.202767833767;13329.200247985153;13329.66675833112;13361.732765344834;13379.866201337862;19831.504212394517;21865.234716784835;21969.23107691223;21984.670229946565;21998.34004979853;7189.604571748604;8389.622466989;8721.781955451164;8748.606312715943;8774.429662071945;979.2264111352094;991.1872264742842;1023.0741179232806;1072.3190773858655;1080.1574771678104;24777.380565485648;28868.711312887022;29644.703552964627;29708.851455742584;30464.84767576148;1060.1783617696192;1097.319661809683;1166.988330116705;1227.2084505493906;1229.1764517773113;13009.739805203842;15147.54557363281;15228.543143705707;15266.236688165547;15483.5354939352;514351;515286.42356787977;522046;522294;522389;51038.48961510412;60095;60712.089318660255;60734;60761.392386076455;1336.953206637763;1369.664432214114;1476.933537990793;1507.4949891786155;1622.294301978643;28693;28835.41963371028;29203.269918252212;29470.852645736635;29631.111066668036;47408.525914740414;47552;47972.52027479681;47980.04039919112;48005.51994480011;307771;311045;311111;311381.4430927831;312155;28838;31794;33168.1707957303;33590.664093358755;33785.32429351448;42119.57880421279;42990.775691822615;43239.56760432355;43327.35008974805;43787.562124378346;35188.47217291692;35767.28465430625;35859.282814343045;35888;36742;35105.82447087748;35141.29717405689;35600;35862;35895.82052089723;35393.29213415769;35869.46195807117;35960.64039359573;36528.45207321946;36851.63148368482;30934.69065309318;31647.84176079105;32626.673733263313;32656.34687306288;32662.673373266913;21220.659140062082;24537.14120005791;24586.139485117936;24928.257891631543;25577.872110639328 17:02:40 INFO - PROCESS | 1668 | |2;jquery.html;364.8668236093825;365.37520839364686;367.6764447286388;376.964188402102;378.04166438079454;19646.312379066734;20368.89815550922;20749.688754668692;21063.578728425422;21445.142194312248;17374.3918962836;20489.487762805937;21160.25939092132;21494.462638434048;21934.780652193473;5088.592912566985;5665.99999999999;5867.618604790697;5926.851828704295;5931.199288096095;3329.6836800504034;4120.1553681495225;5820.505257053155;6144.354842741493;6438.227412710481;6368.203974503187;6375.0437434384985;6391.201099862517;6393.9680301598655;6394.232692076934;6055.2430946131735;6311.842203944915;6318.494520438354;6321.557490975633;6355.491560675158;5428.972855135699;5456.781728730846;5471.534919531845;5498.5876059295715;5502;22708.886455567615;24987.50024999508;26124;26208.60687089695;26364.94540218389;2173.5435558532727;2184.1263494602285;2306.3196357074703;2619.9607005894927;2836.6454193225845;14187.43250269988;14892.180930048842;14999.400023999027;15012.249387530579;15090.849091509164;1699.337258469198;1781.536800431897;1832.6701193785113;1862.366795289608;1924.894130822804;74991.62504187445;94101;95116.52441737747;97335;99459.50270248674;10464.843027354596;10468.110210632181;10532.262741608014;10602.36385816838;10715.571377144983;12667.556635517712;12688.111832171659;12688.49246030167;12696.492140314469;12702.618921432371;8466.576671166416;8654.394192406471;8747.912520874837;8755.912440875638;8759.211670949646;9092.454452732858;9107.316951228684;9126.771830704285;9226;9232.492212928353;6185.505159587222;6303.6217826929615;6305.527085468517;6318.936810631835;6389.424951754364;1827.4883032750852;1839.3378383781828;1846.722991551278;1848.8428483578778;1871.373090014833;872.956816604152;971.0871780526423;987.1313244344931;1064.6213153965632;1099.9945000274947;20231.393058208276;20805.479863003548;20927.37217883466;21091.789082109288;21204.575908481744;957.4446820843893;991.548845275398;995.5470261031359;1021.4841505040056;1088.216484131424;11292.887071129348;11569.942150289195;11572.826407603892;11588.594399195988;11601.07191424684;602895;609219;615804.9209753922;636692;637093;61144.69427652833;63688;94916;98621;100073;1064.669952314785;1068.8503609494676;1088.2436706489073;1089.624079692489;1091.4433638844246;18369.357072502396;18466.81533184651;18759.34342298013;19119.235230591177;19239.03804809726;44540.77729611461;46322;46797.766011169726;47245.76377118092;47455.99999999862;45832.54167458416;64231;64652.70694586176;64701.35298646953;65204.02193967;7490.700371985277;7507.136679281734;7564.357029652321;7574.848503030019;7850.489718168301;11966.700832479257;12067.215630983957;12399.876001239872;12402.627921162379;12506.249625022529;10203.795924081625;10513.316634418738;10826.13390928708;10884.74825395058;10982.780344392908;8597.312215022657;10209.591616335263;10327.277090603586;10463.372197668165;10498.527566259689;10219.642312519027;10347.586096556355;10422.270441069053;10833.079188269085;10878.129749619853;7176.21061683204;7320.26797320285;7394.926050739424;7552.962235188789;7620.3522700571075;9875.654352097643;9941.900580994386;10177.796444071226;10222.591096356062;10275.229357798045 17:02:40 INFO - PROCESS | 1668 | |3;mootools.html;1452.7530319845625;1596.6168119651284;1636.4599682104904;1917.2810196176433;2039.5411032517686;7977.042754869417;8536.615852286646;8692.348073894467;8695.95652021739;8710.04189539151;8517.105703901094;8764.91235087649;8805.86791198131;8811.95594022029;8813.4711917285;2091.9267825626107;2161.9891900540474;2166.967495487569;2349.5888219561593;2475.8762061896923;2499.462615537659;2507.5737124688753;2512.1207577347873;2514.9119780807673;2517.811164162686;2427.514497100578;2480.9751902481016;2496.500699860026;2507.3856905058287;2515.421453065796;2451.595486744685;2500.762427569378;2502.1117503286255;2502.4869901670113;2505.4863752930787;2463.051725085837;2496.163785131983;2503.7996960243318;2507.69907611086;2508.949821003588;8732.650693972297;8811.691590794291;8812.691555795516;8816.074312197192;8823;4705.835295764666;5484.61607687462;5601.467860553282;5650.463205995424;5661;3593.7843729376323;3740.5137332146646;3769.453429252743;3771.509703738524;3781;1003.1022235099618;1016.2987538598344;1033.3799720167915;1049.8372752223418;1080.8162612355918;12034.81947770784;12054.939725301318;12056.397180141046;12061.91442770155;12070.879291207064;4923.384576927884;4936.136176169156;5004;5014.949850501448;5024.869404384058;10166.440845753406;10173.694789156338;10174.796504069876;10185;10188.337758045704;5572.609917305831;5583.246261754693;5590.021746194399;5590.440955904377;5590.468905453975;4476.104779044204;4642.280446530793;4647.604953578916;4652.418447694038;4852.2479015752615;3966.900827479336;4033.495813023372;4092.0383709828166;4106.507219133723;4118.382242663624;1298.9805152922713;1337.1308649377982;1341.7920222365549;1351.6958684295955;1360.4422186903323;1378.0904602961998;1398.1890503508143;1427.4004917934492;1427.4504315838476;1429.428228708504;8113.553754543556;8371.874421883676;8414.863993360941;8421.4947103174;8425.789355266168;1197.694587880085;1207.6497815633368;1208.8791120888018;1228.729679470515;1229.8524177098805;6324.936750632435;6333.46165575922;6406.167198264196;6409.871802563923;6413.551051426355;10625.256232063835;10916.290441121302;10928.071113955402;11030.669079927615;11041.668749937515;8501.574921253912;10680.51937662807;10743.355398675949;10761.730956725987;10914.345139291669;8408.2432581068;8453.239208471266;8515.446495977742;8518.168593752373;8541.01778295504;7328.96335518319;7767.844643107219;7935.40484463656;7996.200379961957;8009.43933924643;9410.153086222273;9689.273304502332;9757.46333951611;9819.16537094355;9845;9887.802243954937;10088.899111008795;10183.694489165337;10189.898101019191;10270.845937311096;7155.356017958201;7155.356017958201;7206.2793720627515;7235.565866047843;7246.094238220223;8361.372897032625;8378.748637540884;8437.198466145706;8459.111793261618;8466.322694184328;7471.103467583925;7586.582737949466;7593.8860917087395;7680.155182929985;7793.142754297136;7416.184219735933;7416.332530072319;7464.514806537558;7577.393808495197;7580.696772129053;7881.251281128284;7930.60346982637;7962.6814927404575;7969.362451004022;7975.720849770462;7058.54119482232;7125.144982602121;7129.536580122275;7129.607871566906;7218.422526197996;7209.675564599711;7242.565446073252;7298.562086274628;7311.013013243144;7312.597807120659 17:02:40 INFO - PROCESS | 1668 | |4;prototype.html;320.5304229304069;322.7434189819095;330.63134604915524;332.3784522942098;333.92152844081636;6541.509386795991;6588.769393071243;6591.110200122982;6600.339966003391;6626.701798419071;6483.837904052401;6510.088587597728;6530.020496925464;6538.803835884931;6544.280129185786;4595.425571803525;4687.671862969593;4717.551832575899;4797.808087676489;4847.830325938584;3961.8217180226957;4073.7352072115364;4698.436187657486;4784.521547845223;4820.963492849037;4748.715077095368;4751.738654374008;4755.762211889409;4759.238521836507;4777.140114779339;4558.977205113986;4631.490536041033;4724.574788269072;4737.573618374328;4743.8339658112145;4040.2121586290687;4041.373587094005;4043.2519983802795;4048.67610591152;4048.8987775305854;6184.412480814315;6198.81403557894;6214.471769899564;6228.283747369065;6234.438900498932;5624.521915637176;5633.57748168889;5636.126400407943;5640.069388550925;5655.26481557399;6624.401350396488;6879.828004299932;6889.655517224119;6919.8962015569805;6934.237233904267;3713.9442908356395;3985.043589538519;4519.570640789119;4609.446866376056;4614.630829533629;7245.311695388929;7264.309890560441;7274.490785645007;7285.7814265572115;7316.890246646304;4655.441347038377;4663.183942810063;4666.370040044619;4667.929981050287;4668.789904454307;4718.457377401574;4723.858284251478;4725.692829966041;4727.385439892861;4735.408073990751;5609.831705048855;5611;5623.943760562423;5707.172459993278;5713.342965558931;6638.900416493757;6875.862482750417;6880.965595171992;6885.724571017103;6990.755323563651;1571.0966194438245;1581.6520365519568;1588.467863265819;1593.6175317923614;1598.1370060167612;963.9710808675751;993.9403635781877;1014.7767491151935;1015.583261350412;1037.4605205293205;6326.620402775756;6378.075179099004;6378.2346118466085;6387.233531976192;6394;7186.892196617055;7191.316824901626;7194.460415468756;7205.855882882418;7233.674484648203;6461.61230326182;6488.480921526361;6488.967555162289;6489.902651460232;6496.415322620986;3081.691830816945;3779.3197224499854;3803.3914573668635;3809.7714137151306;3813.370793819044;7272.818179545554;7521.0974683038385;7521.172671006185;7533.058367704037;7544.434167437465;6540.953447448385;7126.2161162272105;7376.188619251878;7441.218672039467;7510.699572017058;7620.618969051525;7626.025849918537;7628;7631.122420921443;7633.427492938163;7014.368706816411;7262.201157872735;7277.599732014578;7283.635818209174;7301.561906285639;5958.404159584007;7185.353318201387;7210.4231661468;7225.783226503213;7233.4574906883245;7622.428317876071;7642.617869106633;7646.885296720665;7679.424043196671;7680.807979800326;4538.88652783683;4569.977150114228;4585.862424127282;4588.426446694163;4590.380298659772;6981.895271571032;7288.416926645749;7290.380317673057;7303.123625165014;7311.4516411268305;6954.408875245661;7001.614911179934;7015.403690686348;7017.964910175417;7031.613261270679;6967.96516017437;6998.440124790106;6999.965000174967;7004.579725216503;7006.369426751413;6832.043513907957;6834.419074378733;6838.863222735418;6839.384455399038;6841.247462778991;6676.732930682719;6977.686004129725;6996.860062798613;7033.331833475811;7033.437325013884;5633.647924498173;5962.612430192197;6117.449429551361;6557.901631475627;6836.213835408992 17:02:40 INFO - PROCESS | 1668 | |5;yui.html;701.4984286235341;702.192478649553;728.2323993967776;760.2473551184329;777.5529070784297;16356.446137616907;17532.29870805168;17860.464186074405;17882.731759023623;17912.283508659653;14832.6291842704;16460.024797024344;16487.258073386696;16523.00861948284;16543.503694889143;755.6561764397186;765.5980610179645;848.8455700247658;887.2148148888248;909.6452383570396;935.8081593273363;950.5959967014026;951.0109486134412;951.9000504946985;953.4946478366443;995.1044060345674;1007.8840933292655;1009.5860697114184;1010.8079464901682;1012.9139023183038;773.9729109481169;777.3819813248481;967.3035414501547;983.9950800246024;997.0179373317277;688.4939569416475;729.489357449782;735.2133217457323;735.2867718313281;735.9705611775522;15688.921555392264;15718.764218536731;15734.68530629381;15745.921270393575;15747.212639368101;12317.260964342167;12346.197497162655;12394.194377365442;12566.874331256662;12656.114072014963;8282.958585207096;10133.543990520444;10150.441725705083;10167.644132455402;10422.687319380431;3400.1159698478327;3454.222799870034;3506.386382383073;3515.806630635314;3700.3524383232825;80877;81330.78003829948;81407.59296203482;81573.59213203956;81621;9351.205147562398;9391.765205869771;9393.154616084585;9606.711798646053;9681.757956051155;21708.13167473315;21718.23986160477;21725.348239552837;21747.782522174577;21754.89122554377;7440.441966852508;7561.054868141482;7602.771916842504;7668.65491052904;7748.806279842936;4004.4593979812935;4071.572484889103;4125.876223713293;4151.750894946313;4223.049813791934;3601.981990090033;3635.709143268532;3647.0517665407187;3652.6164752701116;3662.5788034376387;557.8744782424003;652.663878102768;655.0567183256096;661.7882277671099;679.1137565477001;503.26381385159897;504.97980080796356;581.7265885034027;611.0925275965188;622.4864486798409;7949;7958.92554505146;8264.752057438287;8305.294050005696;8353.916460835435;576.9048107062372;579.3771695427348;607.9604825686405;625.1154616218091;625.1778910732372;3688;3805.82873770681;3837.865674701372;3844.884653460401;3870.071182916136;82970.58514707508;85318;89844.20311593862;90200.54899725459;90499.54750226339;79758;80515.59742201252;80537.59731201307;80644;81293;51120.744396278526;69291.65354173197;69393.65303173552;69910.25224369533;70491.00826429708;6497;7517.849643007218;7793.844123117619;7921.128675845537;8078.959605201936;47559.286610700176;48559;49920.25119623136;50653;51620.22569661383;65820;66605.66697166677;67679.32320676927;72352;72558;11215.71960700989;11363.261388009752;11373.31760094397;11597.246178998676;11865;11918.106142039209;11956.402179890796;12056.879431205576;12218.75562488763;12312.26126432417;9912.752181195528;11558.133140014701;11612.606487221587;11998;12025.278483291031;11459.656210313704;11611.187216894736;11669.708257293636;11731.941340293244;11850.111241656738;9336.486493242937;11371.317720936438;11418.257813242113;11432.199746017699;11895.226810257305;9603.711888643353;9763.658271960732;9814.214862810815;9902.801943961225;10247.436390998566;7764.301212890867;9735.951320243354;9819.803603928025;9846.852297215692;9908.950455247677 17:02:40 INFO - PROCESS | 1668 | __end_tp_report 17:02:40 INFO - PROCESS | 1668 | __start_cc_report 17:02:40 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1121 17:02:40 INFO - PROCESS | 1668 | __end_cc_report 17:02:40 INFO - PROCESS | 1668 | __startTimestamp1458691360013__endTimestamp 17:02:40 INFO - PROCESS | 1668 | 17:02:40 INFO - PROCESS | 1668 | ------- Summary: start ------- 17:02:40 INFO - PROCESS | 1668 | Number of tests: 6 17:02:40 INFO - PROCESS | 1668 | 17:02:40 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5563.60 Median:5681.97 stddev:637.68 (11.2%) stddev-sans-first:603.40 17:02:40 INFO - PROCESS | 1668 | Values: 2862.2 3273.5 3455.4 3470.4 3492.4 4778.6 5326.6 5435.5 5519.1 5526.9 5513.8 5530.1 5580.3 5614.7 5628.9 4955.8 4956.9 4974.6 4995.4 5166.9 5090.8 5101.1 5111.8 5118.0 5145.0 4953.7 4959.1 5048.4 5054.3 5062.1 5039.8 5044.6 5060.5 5070.6 5093.5 4984.5 4991.0 5007.7 5031.4 5035.7 5262.0 5346.6 5360.8 5391.3 5474.5 6080.9 6092.5 6097.9 6098.4 6117.0 6067.6 6147.6 6166.3 6166.3 6174.4 5477.3 5558.4 5644.4 5666.3 5668.4 6096.3 6244.0 6254.6 6256.6 6259.7 5866.1 5878.5 5885.8 5926.0 5971.0 5953.7 6077.1 6105.4 6105.5 6137.7 5970.5 6038.5 6038.6 6057.8 6093.3 6033.1 6034.0 6037.0 6045.2 6064.8 4606.4 4762.5 4773.0 4776.5 4782.3 4264.8 4295.1 4318.1 4354.6 4394.8 5416.2 5446.5 5487.5 5499.3 5539.2 5918.6 5950.9 6115.3 6123.4 6136.1 5410.6 5472.1 5477.0 5485.2 5524.7 5174.1 5181.5 5194.4 5198.7 5260.0 6121.8 6177.1 6294.9 6312.4 6312.7 6087.2 6139.3 6163.8 6175.6 6175.9 6270.5 6292.1 6292.5 6294.2 6306.6 5849.2 5960.9 6008.7 6021.8 6065.1 5980.3 6059.5 6069.5 6114.9 6137.7 4929.1 5531.8 5679.9 5851.7 5863.4 5682.0 5818.0 5824.0 5856.3 5861.6 5621.1 5721.3 5770.8 5800.3 5817.4 5838.8 5868.8 5870.3 5870.8 5937.2 5577.6 5670.6 5677.7 5738.6 5740.1 17:02:40 INFO - PROCESS | 1668 | 17:02:40 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:55933.87 Median:35878.73 stddev:107778.75 (300.4%) stddev-sans-first:108000.57 17:02:40 INFO - PROCESS | 1668 | Values: 441.2 568.7 576.2 630.0 641.6 29812.3 40421.0 40955.0 40956.0 41005.4 43437.6 44104.8 44122.8 44139.7 44238.0 7701.6 8197.7 9786.8 9820.0 9843.2 9693.0 9812.3 9826.2 9834.9 9852.0 9779.9 10001.6 10006.6 10014.2 10022.9 9872.0 9894.6 9902.0 9936.6 9977.6 8327.8 9786.3 9816.8 9834.8 9980.5 40875.0 40877.8 40930.0 41358.8 41798.0 3287.2 3418.4 3458.4 3534.2 3810.8 24248.8 29221.4 30138.4 30558.0 30741.0 2680.7 2692.1 2735.8 2790.4 2875.8 359024.0 360478.0 360495.0 361296.0 361594.2 63439.0 63915.0 64503.0 64523.7 64875.4 67923.0 67990.0 68110.7 68191.3 68296.3 17176.7 17751.2 17759.3 17762.3 17794.1 13287.2 13329.2 13329.7 13361.7 13379.9 19831.5 21865.2 21969.2 21984.7 21998.3 7189.6 8389.6 8721.8 8748.6 8774.4 979.2 991.2 1023.1 1072.3 1080.2 24777.4 28868.7 29644.7 29708.9 30464.8 1060.2 1097.3 1167.0 1227.2 1229.2 13009.7 15147.5 15228.5 15266.2 15483.5 514351.0 515286.4 522046.0 522294.0 522389.0 51038.5 60095.0 60712.1 60734.0 60761.4 1337.0 1369.7 1476.9 1507.5 1622.3 28693.0 28835.4 29203.3 29470.9 29631.1 47408.5 47552.0 47972.5 47980.0 48005.5 307771.0 311045.0 311111.0 311381.4 312155.0 28838.0 31794.0 33168.2 33590.7 33785.3 42119.6 42990.8 43239.6 43327.4 43787.6 35188.5 35767.3 35859.3 35888.0 36742.0 35105.8 35141.3 35600.0 35862.0 35895.8 35393.3 35869.5 35960.6 36528.5 36851.6 30934.7 31647.8 32626.7 32656.3 32662.7 21220.7 24537.1 24586.1 24928.3 25577.9 17:02:40 INFO - PROCESS | 1668 | 17:02:40 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:33072.27 Median:14600.80 stddev:101860.40 (697.6%) stddev-sans-first:102116.54 17:02:40 INFO - PROCESS | 1668 | Values: 364.9 365.4 367.7 377.0 378.0 19646.3 20368.9 20749.7 21063.6 21445.1 17374.4 20489.5 21160.3 21494.5 21934.8 5088.6 5666.0 5867.6 5926.9 5931.2 3329.7 4120.2 5820.5 6144.4 6438.2 6368.2 6375.0 6391.2 6394.0 6394.2 6055.2 6311.8 6318.5 6321.6 6355.5 5429.0 5456.8 5471.5 5498.6 5502.0 22708.9 24987.5 26124.0 26208.6 26364.9 2173.5 2184.1 2306.3 2620.0 2836.6 14187.4 14892.2 14999.4 15012.2 15090.8 1699.3 1781.5 1832.7 1862.4 1924.9 74991.6 94101.0 95116.5 97335.0 99459.5 10464.8 10468.1 10532.3 10602.4 10715.6 12667.6 12688.1 12688.5 12696.5 12702.6 8466.6 8654.4 8747.9 8755.9 8759.2 9092.5 9107.3 9126.8 9226.0 9232.5 6185.5 6303.6 6305.5 6318.9 6389.4 1827.5 1839.3 1846.7 1848.8 1871.4 873.0 971.1 987.1 1064.6 1100.0 20231.4 20805.5 20927.4 21091.8 21204.6 957.4 991.5 995.5 1021.5 1088.2 11292.9 11569.9 11572.8 11588.6 11601.1 602895.0 609219.0 615804.9 636692.0 637093.0 61144.7 63688.0 94916.0 98621.0 100073.0 1064.7 1068.9 1088.2 1089.6 1091.4 18369.4 18466.8 18759.3 19119.2 19239.0 44540.8 46322.0 46797.8 47245.8 47456.0 45832.5 64231.0 64652.7 64701.4 65204.0 7490.7 7507.1 7564.4 7574.8 7850.5 11966.7 12067.2 12399.9 12402.6 12506.2 10203.8 10513.3 10826.1 10884.7 10982.8 8597.3 10209.6 10327.3 10463.4 10498.5 10219.6 10347.6 10422.3 10833.1 10878.1 7176.2 7320.3 7394.9 7553.0 7620.4 9875.7 9941.9 10177.8 10222.6 10275.2 17:02:40 INFO - PROCESS | 1668 | 17:02:40 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:6135.09 Median:4970.07 stddev:3205.98 (64.5%) stddev-sans-first:3195.65 17:02:40 INFO - PROCESS | 1668 | Values: 1452.8 1596.6 1636.5 1917.3 2039.5 7977.0 8536.6 8692.3 8696.0 8710.0 8517.1 8764.9 8805.9 8812.0 8813.5 2091.9 2162.0 2167.0 2349.6 2475.9 2499.5 2507.6 2512.1 2514.9 2517.8 2427.5 2481.0 2496.5 2507.4 2515.4 2451.6 2500.8 2502.1 2502.5 2505.5 2463.1 2496.2 2503.8 2507.7 2508.9 8732.7 8811.7 8812.7 8816.1 8823.0 4705.8 5484.6 5601.5 5650.5 5661.0 3593.8 3740.5 3769.5 3771.5 3781.0 1003.1 1016.3 1033.4 1049.8 1080.8 12034.8 12054.9 12056.4 12061.9 12070.9 4923.4 4936.1 5004.0 5014.9 5024.9 10166.4 10173.7 10174.8 10185.0 10188.3 5572.6 5583.2 5590.0 5590.4 5590.5 4476.1 4642.3 4647.6 4652.4 4852.2 3966.9 4033.5 4092.0 4106.5 4118.4 1299.0 1337.1 1341.8 1351.7 1360.4 1378.1 1398.2 1427.4 1427.5 1429.4 8113.6 8371.9 8414.9 8421.5 8425.8 1197.7 1207.6 1208.9 1228.7 1229.9 6324.9 6333.5 6406.2 6409.9 6413.6 10625.3 10916.3 10928.1 11030.7 11041.7 8501.6 10680.5 10743.4 10761.7 10914.3 8408.2 8453.2 8515.4 8518.2 8541.0 7329.0 7767.8 7935.4 7996.2 8009.4 9410.2 9689.3 9757.5 9819.2 9845.0 9887.8 10088.9 10183.7 10189.9 10270.8 7155.4 7155.4 7206.3 7235.6 7246.1 8361.4 8378.7 8437.2 8459.1 8466.3 7471.1 7586.6 7593.9 7680.2 7793.1 7416.2 7416.3 7464.5 7577.4 7580.7 7881.3 7930.6 7962.7 7969.4 7975.7 7058.5 7125.1 7129.5 7129.6 7218.4 7209.7 7242.6 7298.6 7311.0 7312.6 17:02:40 INFO - PROCESS | 1668 | 17:02:40 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5682.05 Median:6489.44 stddev:1841.01 (28.4%) stddev-sans-first:1801.65 17:02:40 INFO - PROCESS | 1668 | Values: 320.5 322.7 330.6 332.4 333.9 6541.5 6588.8 6591.1 6600.3 6626.7 6483.8 6510.1 6530.0 6538.8 6544.3 4595.4 4687.7 4717.6 4797.8 4847.8 3961.8 4073.7 4698.4 4784.5 4821.0 4748.7 4751.7 4755.8 4759.2 4777.1 4559.0 4631.5 4724.6 4737.6 4743.8 4040.2 4041.4 4043.3 4048.7 4048.9 6184.4 6198.8 6214.5 6228.3 6234.4 5624.5 5633.6 5636.1 5640.1 5655.3 6624.4 6879.8 6889.7 6919.9 6934.2 3713.9 3985.0 4519.6 4609.4 4614.6 7245.3 7264.3 7274.5 7285.8 7316.9 4655.4 4663.2 4666.4 4667.9 4668.8 4718.5 4723.9 4725.7 4727.4 4735.4 5609.8 5611.0 5623.9 5707.2 5713.3 6638.9 6875.9 6881.0 6885.7 6990.8 1571.1 1581.7 1588.5 1593.6 1598.1 964.0 993.9 1014.8 1015.6 1037.5 6326.6 6378.1 6378.2 6387.2 6394.0 7186.9 7191.3 7194.5 7205.9 7233.7 6461.6 6488.5 6489.0 6489.9 6496.4 3081.7 3779.3 3803.4 3809.8 3813.4 7272.8 7521.1 7521.2 7533.1 7544.4 6541.0 7126.2 7376.2 7441.2 7510.7 7620.6 7626.0 7628.0 7631.1 7633.4 7014.4 7262.2 7277.6 7283.6 7301.6 5958.4 7185.4 7210.4 7225.8 7233.5 7622.4 7642.6 7646.9 7679.4 7680.8 4538.9 4570.0 4585.9 4588.4 4590.4 6981.9 7288.4 7290.4 7303.1 7311.5 6954.4 7001.6 7015.4 7018.0 7031.6 6968.0 6998.4 7000.0 7004.6 7006.4 6832.0 6834.4 6838.9 6839.4 6841.2 6676.7 6977.7 6996.9 7033.3 7033.4 5633.6 5962.6 6117.4 6557.9 6836.2 17:02:40 INFO - PROCESS | 1668 | 17:02:40 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:18324.90 Median:616.79 stddev:25412.02 (4120.0%) stddev-sans-first:25448.85 17:02:40 INFO - PROCESS | 1668 | Values: 701.5 702.2 728.2 760.2 777.6 16356.4 17532.3 17860.5 17882.7 17912.3 14832.6 16460.0 16487.3 16523.0 16543.5 755.7 765.6 848.8 887.2 909.6 935.8 950.6 951.0 951.9 953.5 995.1 1007.9 1009.6 1010.8 1012.9 774.0 777.4 967.3 984.0 997.0 688.5 729.5 735.2 735.3 736.0 15688.9 15718.8 15734.7 15745.9 15747.2 12317.3 12346.2 12394.2 12566.9 12656.1 8283.0 10133.5 10150.4 10167.6 10422.7 3400.1 3454.2 3506.4 3515.8 3700.4 80877.0 81330.8 81407.6 81573.6 81621.0 9351.2 9391.8 9393.2 9606.7 9681.8 21708.1 21718.2 21725.3 21747.8 21754.9 7440.4 7561.1 7602.8 7668.7 7748.8 4004.5 4071.6 4125.9 4151.8 4223.0 3602.0 3635.7 3647.1 3652.6 3662.6 557.9 652.7 655.1 661.8 679.1 503.3 505.0 581.7 611.1 622.5 7949.0 7958.9 8264.8 8305.3 8353.9 576.9 579.4 608.0 625.1 625.2 3688.0 3805.8 3837.9 3844.9 3870.1 82970.6 85318.0 89844.2 90200.5 90499.5 79758.0 80515.6 80537.6 80644.0 81293.0 51120.7 69291.7 69393.7 69910.3 70491.0 6497.0 7517.8 7793.8 7921.1 8079.0 47559.3 48559.0 49920.3 50653.0 51620.2 65820.0 66605.7 67679.3 72352.0 72558.0 11215.7 11363.3 11373.3 11597.2 11865.0 11918.1 11956.4 12056.9 12218.8 12312.3 9912.8 11558.1 11612.6 11998.0 12025.3 11459.7 11611.2 11669.7 11731.9 11850.1 9336.5 11371.3 11418.3 11432.2 11895.2 9603.7 9763.7 9814.2 9902.8 10247.4 7764.3 9736.0 9819.8 9846.9 9909.0 17:02:40 INFO - PROCESS | 1668 | -------- Summary: end -------- 17:02:40 INFO - PROCESS | 1668 | 17:02:40 INFO - TEST-INFO | 1668: exit 0 17:02:40 INFO - TEST-OK | dromaeo_css | took 1113304ms 17:02:40 INFO - TEST-START | kraken 17:02:40 INFO - operating with platform_type : mac_ 17:02:40 INFO - Initialising browser for kraken test... 17:02:40 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/tmp6wHcAD/profile http://localhost:49272/getInfo.html) 17:02:42 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 17:02:42 INFO - PROCESS | 1678 | colorDepth:24 17:02:42 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 17:02:42 INFO - PROCESS | 1678 | __metrics 17:02:42 INFO - PROCESS | 1678 | 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" 17:02:42 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:02:43 INFO - TEST-INFO | 1678: exit 0 17:02:43 INFO - Browser initialized. 17:02:43 INFO - Running cycle 1/1 for kraken test... 17:02:43 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/T/tmp6wHcAD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:02:55 INFO - PROCESS | 1680 | RSS: Main: 247361536 17:02:55 INFO - PROCESS | 1680 | 17:02:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 17:02:58 INFO - PROCESS | 1680 | RSS: Main: 294039552 17:02:58 INFO - PROCESS | 1680 | 17:03:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 17:03:01 INFO - PROCESS | 1680 | RSS: Main: 303783936 17:03:01 INFO - PROCESS | 1680 | 17:03:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 17:03:05 INFO - PROCESS | 1680 | RSS: Main: 288129024 17:03:05 INFO - PROCESS | 1680 | 17:03:07 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 17:03:08 INFO - PROCESS | 1680 | RSS: Main: 290480128 17:03:08 INFO - PROCESS | 1680 | 17:03:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:03:10 INFO - PROCESS | 1680 | RSS: Main: 292171776 17:03:10 INFO - PROCESS | 1680 | 17:03:13 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 17:03:14 INFO - PROCESS | 1680 | RSS: Main: 292429824 17:03:14 INFO - PROCESS | 1680 | 17:03:17 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 17:03:18 INFO - PROCESS | 1680 | RSS: Main: 293191680 17:03:18 INFO - PROCESS | 1680 | 17:03:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 17:03:21 INFO - PROCESS | 1680 | RSS: Main: 293871616 17:03:21 INFO - PROCESS | 1680 | 17:03:22 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:03:23 INFO - PROCESS | 1680 | RSS: Main: 327917568 17:03:23 INFO - PROCESS | 1680 | 17:03:25 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:03:26 INFO - PROCESS | 1680 | RSS: Main: 327282688 17:03:26 INFO - PROCESS | 1680 | 17:03:28 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:03:28 INFO - PROCESS | 1680 | RSS: Main: 333643776 17:03:28 INFO - PROCESS | 1680 | 17:03:31 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:03:31 INFO - PROCESS | 1680 | RSS: Main: 336748544 17:03:31 INFO - PROCESS | 1680 | 17:03:34 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:03:35 INFO - PROCESS | 1680 | RSS: Main: 336789504 17:03:35 INFO - PROCESS | 1680 | 17:03:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 17:03:37 INFO - PROCESS | 1680 | RSS: Main: 396275712 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | __start_tp_report 17:03:37 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 17:03:37 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 17:03:37 INFO - PROCESS | 1680 | |i|pagename|runs| 17:03:37 INFO - PROCESS | 1680 | |0;ai-astar;110;108;108;110;110;112;116;111;110;111 17:03:37 INFO - PROCESS | 1680 | |1;audio-beat-detection;152;117;146;156;116;138;137;116;152;118 17:03:37 INFO - PROCESS | 1680 | |2;audio-dft;182;188;187;187;187;186;187;186;181;175 17:03:37 INFO - PROCESS | 1680 | |3;audio-fft;76;73;88;76;56;60;64;98;101;81 17:03:37 INFO - PROCESS | 1680 | |4;audio-oscillator;92;101;127;81;78;81;118;80;80;120 17:03:37 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;97;128;116;83;96;130;89;84;87;83 17:03:37 INFO - PROCESS | 1680 | |6;imaging-darkroom;94;97;92;93;95;141;148;109;93;93 17:03:37 INFO - PROCESS | 1680 | |7;imaging-desaturate;87;86;87;87;86;86;87;85;87;84 17:03:37 INFO - PROCESS | 1680 | |8;json-parse-financial;57;55;54;55;56;54;55;64;56;54 17:03:37 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;75;67;75;75;73;70;67;75;92;100 17:03:37 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;84;81;83;82;82;80;104;81;83;82 17:03:37 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;133;130;133;129;109;123;117;130;120;122 17:03:37 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;157;175;140;139;139;168;176;135;140;141 17:03:37 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;67;66;65;68;66;69;67;66;66;65 17:03:37 INFO - PROCESS | 1680 | __end_tp_report 17:03:37 INFO - PROCESS | 1680 | __start_cc_report 17:03:37 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1233 17:03:37 INFO - PROCESS | 1680 | __end_cc_report 17:03:37 INFO - PROCESS | 1680 | __startTimestamp1458691417087__endTimestamp 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | ------- Summary: start ------- 17:03:37 INFO - PROCESS | 1680 | Number of tests: 14 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:110.60 Median:110.50 stddev:2.27 (2.1%) stddev-sans-first:2.40 17:03:37 INFO - PROCESS | 1680 | Values: 110.0 108.0 108.0 110.0 110.0 112.0 116.0 111.0 110.0 111.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:142.00 stddev:16.63 (11.7%) stddev-sans-first:16.43 17:03:37 INFO - PROCESS | 1680 | Values: 152.0 117.0 146.0 156.0 116.0 138.0 137.0 116.0 152.0 118.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:184.60 Median:187.00 stddev:4.09 (2.2%) stddev-sans-first:4.23 17:03:37 INFO - PROCESS | 1680 | Values: 182.0 188.0 187.0 187.0 187.0 186.0 187.0 186.0 181.0 175.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:77.30 Median:76.00 stddev:15.17 (20.0%) stddev-sans-first:16.08 17:03:37 INFO - PROCESS | 1680 | Values: 76.0 73.0 88.0 76.0 56.0 60.0 64.0 98.0 101.0 81.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:80.00 stddev:19.29 (24.1%) stddev-sans-first:20.41 17:03:37 INFO - PROCESS | 1680 | Values: 92.0 101.0 127.0 81.0 78.0 81.0 118.0 80.0 80.0 120.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:99.30 Median:85.50 stddev:18.51 (21.7%) stddev-sans-first:19.62 17:03:37 INFO - PROCESS | 1680 | Values: 97.0 128.0 116.0 83.0 96.0 130.0 89.0 84.0 87.0 83.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:93.00 stddev:21.20 (22.8%) stddev-sans-first:22.07 17:03:37 INFO - PROCESS | 1680 | Values: 94.0 97.0 92.0 93.0 95.0 141.0 148.0 109.0 93.0 93.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:87.00 stddev:1.03 (1.2%) stddev-sans-first:1.05 17:03:37 INFO - PROCESS | 1680 | Values: 87.0 86.0 87.0 87.0 86.0 86.0 87.0 85.0 87.0 84.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:55.50 stddev:2.98 (5.4%) stddev-sans-first:3.14 17:03:37 INFO - PROCESS | 1680 | Values: 57.0 55.0 54.0 55.0 56.0 54.0 55.0 64.0 56.0 54.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:76.90 Median:75.00 stddev:10.72 (14.3%) stddev-sans-first:11.35 17:03:37 INFO - PROCESS | 1680 | Values: 75.0 67.0 75.0 75.0 73.0 70.0 67.0 75.0 92.0 100.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:82.00 stddev:7.05 (8.6%) stddev-sans-first:7.48 17:03:37 INFO - PROCESS | 1680 | Values: 84.0 81.0 83.0 82.0 82.0 80.0 104.0 81.0 83.0 82.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:124.60 Median:129.50 stddev:7.82 (6.0%) stddev-sans-first:7.68 17:03:37 INFO - PROCESS | 1680 | Values: 133.0 130.0 133.0 129.0 109.0 123.0 117.0 130.0 120.0 122.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.00 Median:149.00 stddev:16.37 (11.0%) stddev-sans-first:17.22 17:03:37 INFO - PROCESS | 1680 | Values: 157.0 175.0 140.0 139.0 139.0 168.0 176.0 135.0 140.0 141.0 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.50 stddev:1.27 (1.9%) stddev-sans-first:1.33 17:03:37 INFO - PROCESS | 1680 | Values: 67.0 66.0 65.0 68.0 66.0 69.0 67.0 66.0 66.0 65.0 17:03:37 INFO - PROCESS | 1680 | -------- Summary: end -------- 17:03:37 INFO - PROCESS | 1680 | 17:03:37 INFO - TEST-INFO | 1680: exit 0 17:03:37 INFO - TEST-OK | kraken | took 57437ms 17:03:37 INFO - SUITE-END | took 1170s 17:03:38 INFO - Completed test suite (00:19:30) 17:03:38 INFO - javascript benchmark 17:03:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2862.227198656363, 3273.4598791199446, 3455.378031954247, 3470.3926812807786, 3492.4062909305417, 4778.569928706415, 5326.627136100475, 5435.456454354572, 5519.061759500884, 5526.944730552693, 5513.807016754415, 5530.142827861688, 5580.330360356753, 5614.691191984439, 5628.859278518029, 4955.801767929279, 4956.851294461172, 4974.602031837444, 4995.425526064513, 5166.9224961625605, 5090.847274581768, 5101.132807422747, 5111.846644600668, 5117.974410127944, 5145.048166089286, 4953.653244272902, 4959.107360675077, 5048.444671086196, 5054.3176671149295, 5062.139436295838, 5039.823606173803, 5044.621653376012, 5060.544550990392, 5070.569001634829, 5093.516115968977, 4984.5264699853815, 4991.0, 5007.724575148365, 5031.421386540558, 5035.748212589356, 5262.0, 5346.625736198468, 5360.758765855546, 5391.32608423947, 5474.452554744534, 6080.939609360194, 6092.482139018144, 6097.93286174922, 6098.3596722343955, 6116.96941515294, 6067.605605635619, 6147.631142131487, 6166.290876549209, 6166.321704612489, 6174.351693072253, 5477.315335583052, 5558.388577256498, 5644.379118296984, 5666.320041594994, 5668.376478587352, 6096.254562767794, 6244.0, 6254.593451425642, 6256.593321434092, 6259.655718935503, 5866.090755932837, 5878.470937615635, 5885.823425297162, 5926.0, 5970.9701451492465, 5953.672548009815, 6077.149199112039, 6105.389461053948, 6105.542084343692, 6137.662428566472, 5970.492508136857, 6038.486728628116, 6038.607490513103, 6057.787977420858, 6093.329733729286, 6033.125196846433, 6034.0043892758085, 6037.0, 6045.21412216409, 6064.818055458343, 4606.401167848158, 4762.547557981986, 4773.0453909218295, 4776.47458779534, 4782.282657601318, 4264.829406823755, 4295.076558539929, 4318.050028993616, 4354.608085272341, 4394.8242070317465, 5416.187571864252, 5446.482584154499, 5487.451254874481, 5499.285092937973, 5539.19681646159, 5918.615290006136, 5950.880982380415, 6115.296740874856, 6123.387661233841, 6136.079588061826, 5410.567154627699, 5472.124460086367, 5477.0, 5485.17722341644, 5524.723763811793, 5174.09453345663, 5181.5077567631015, 5194.35070616173, 5198.688078715213, 5259.973700131552, 6121.84695382619, 6177.073438984188, 6294.937050629526, 6312.400321969498, 6312.652804095819, 6087.208662873975, 6139.338817383189, 6163.7842675506145, 6175.5677102602385, 6175.907361389673, 6270.467010304175, 6292.056191571301, 6292.528060395397, 6294.213223347082, 6306.621602703853, 5849.210356601974, 5960.850978725566, 6008.699565021819, 6021.849453763691, 6065.090236464565, 5980.282366116094, 6059.454649081604, 6069.544784141295, 6114.938850611437, 6137.662428566293, 4929.088118698052, 5531.806386776604, 5679.943200567942, 5851.678157701367, 5863.384344643751, 5681.971590142023, 5817.970910145422, 5824.0, 5856.297244330707, 5861.6189947654975, 5621.128725047624, 5721.313442386941, 5770.798022069207, 5800.274965629296, 5817.447342502455, 5838.824835254949, 5868.794592189253, 5870.26621672291, 5870.823875283749, 5937.168796368425, 5577.581681373832, 5670.574706896917, 5677.744501497526, 5738.598298119092, 5740.1102829061565], "unit": "score", "name": "dojo.html", "value": 5522.401564867707}, {"lowerIsBetter": false, "replicates": [441.1926175099568, 568.6531215958265, 576.216345769753, 629.9937000629996, 641.5541198866788, 29812.254693632643, 40421.0, 40955.0, 40956.0, 41005.38491922616, 43437.56562434375, 44104.77947610257, 44122.7793861031, 44139.67580972568, 44238.0, 7701.614919254043, 8197.7130800422, 9786.755331116708, 9820.01799820016, 9843.163331116864, 9692.95153524235, 9812.313138080337, 9826.16477599405, 9834.852477212848, 9852.0, 9779.902200978006, 10001.59993600255, 10006.59973601055, 10014.248931330143, 10022.899771002307, 9871.950640246825, 9894.604215831358, 9902.0, 9936.602535898519, 9977.551010204557, 8327.750167494924, 9786.266030047775, 9816.754581135528, 9834.754131146707, 9980.500974951223, 40874.9781255471, 40877.795611022055, 40930.0, 41358.793206034075, 41798.0, 3287.2439338952177, 3418.4188687922865, 3458.39478091333, 3534.2224710563637, 3810.771353718758, 24248.757512424825, 29221.41557168866, 30138.397232055457, 30558.0, 30741.0, 2680.6515153030177, 2692.0846912049997, 2735.7811375089946, 2790.3861150546845, 2875.8130721503035, 359024.0, 360478.0, 360495.0, 361296.0, 361594.19202904083, 63439.0, 63915.0, 64503.0, 64523.67738161279, 64875.35124648787, 67923.0, 67989.98015029778, 68110.65944670344, 68191.31808681949, 68296.31703682999, 17176.742348864776, 17751.201195946214, 17759.289628414976, 17762.28950841952, 17794.110294485483, 13287.202767833767, 13329.200247985153, 13329.66675833112, 13361.732765344834, 13379.866201337862, 19831.504212394517, 21865.234716784835, 21969.23107691223, 21984.670229946565, 21998.34004979853, 7189.604571748604, 8389.622466989, 8721.781955451164, 8748.606312715943, 8774.429662071945, 979.2264111352094, 991.1872264742842, 1023.0741179232806, 1072.3190773858655, 1080.1574771678104, 24777.380565485648, 28868.711312887022, 29644.703552964627, 29708.851455742584, 30464.84767576148, 1060.1783617696192, 1097.319661809683, 1166.988330116705, 1227.2084505493906, 1229.1764517773113, 13009.739805203842, 15147.54557363281, 15228.543143705707, 15266.236688165547, 15483.5354939352, 514351.0, 515286.42356787977, 522046.0, 522294.0, 522389.0, 51038.48961510412, 60095.0, 60712.089318660255, 60734.0, 60761.392386076455, 1336.953206637763, 1369.664432214114, 1476.933537990793, 1507.4949891786155, 1622.294301978643, 28693.0, 28835.41963371028, 29203.269918252212, 29470.852645736635, 29631.111066668036, 47408.525914740414, 47552.0, 47972.52027479681, 47980.04039919112, 48005.51994480011, 307771.0, 311045.0, 311111.0, 311381.4430927831, 312155.0, 28838.0, 31794.0, 33168.1707957303, 33590.664093358755, 33785.32429351448, 42119.57880421279, 42990.775691822615, 43239.56760432355, 43327.35008974805, 43787.562124378346, 35188.47217291692, 35767.28465430625, 35859.282814343045, 35888.0, 36742.0, 35105.82447087748, 35141.29717405689, 35600.0, 35862.0, 35895.82052089723, 35393.29213415769, 35869.46195807117, 35960.64039359573, 36528.45207321946, 36851.63148368482, 30934.69065309318, 31647.84176079105, 32626.673733263313, 32656.34687306288, 32662.673373266913, 21220.659140062082, 24537.14120005791, 24586.139485117936, 24928.257891631543, 25577.872110639328], "unit": "score", "name": "ext.html", "value": 19554.914772760265}, {"lowerIsBetter": false, "replicates": [364.8668236093825, 365.37520839364686, 367.6764447286388, 376.964188402102, 378.04166438079454, 19646.312379066734, 20368.89815550922, 20749.688754668692, 21063.578728425422, 21445.142194312248, 17374.3918962836, 20489.487762805937, 21160.25939092132, 21494.462638434048, 21934.780652193473, 5088.592912566985, 5665.99999999999, 5867.618604790697, 5926.851828704295, 5931.199288096095, 3329.6836800504034, 4120.1553681495225, 5820.505257053155, 6144.354842741493, 6438.227412710481, 6368.203974503187, 6375.0437434384985, 6391.201099862517, 6393.9680301598655, 6394.232692076934, 6055.2430946131735, 6311.842203944915, 6318.494520438354, 6321.557490975633, 6355.491560675158, 5428.972855135699, 5456.781728730846, 5471.534919531845, 5498.5876059295715, 5502.0, 22708.886455567615, 24987.50024999508, 26124.0, 26208.60687089695, 26364.94540218389, 2173.5435558532727, 2184.1263494602285, 2306.3196357074703, 2619.9607005894927, 2836.6454193225845, 14187.43250269988, 14892.180930048842, 14999.400023999027, 15012.249387530579, 15090.849091509164, 1699.337258469198, 1781.536800431897, 1832.6701193785113, 1862.366795289608, 1924.894130822804, 74991.62504187445, 94101.0, 95116.52441737747, 97335.0, 99459.50270248674, 10464.843027354596, 10468.110210632181, 10532.262741608014, 10602.36385816838, 10715.571377144983, 12667.556635517712, 12688.111832171659, 12688.49246030167, 12696.492140314469, 12702.618921432371, 8466.576671166416, 8654.394192406471, 8747.912520874837, 8755.912440875638, 8759.211670949646, 9092.454452732858, 9107.316951228684, 9126.771830704285, 9226.0, 9232.492212928353, 6185.505159587222, 6303.6217826929615, 6305.527085468517, 6318.936810631835, 6389.424951754364, 1827.4883032750852, 1839.3378383781828, 1846.722991551278, 1848.8428483578778, 1871.373090014833, 872.956816604152, 971.0871780526423, 987.1313244344931, 1064.6213153965632, 1099.9945000274947, 20231.393058208276, 20805.479863003548, 20927.37217883466, 21091.789082109288, 21204.575908481744, 957.4446820843893, 991.548845275398, 995.5470261031359, 1021.4841505040056, 1088.216484131424, 11292.887071129348, 11569.942150289195, 11572.826407603892, 11588.594399195988, 11601.07191424684, 602895.0, 609219.0, 615804.9209753922, 636692.0, 637093.0, 61144.69427652833, 63688.0, 94916.0, 98621.0, 100073.0, 1064.669952314785, 1068.8503609494676, 1088.2436706489073, 1089.624079692489, 1091.4433638844246, 18369.357072502396, 18466.81533184651, 18759.34342298013, 19119.235230591177, 19239.03804809726, 44540.77729611461, 46322.0, 46797.766011169726, 47245.76377118092, 47455.99999999862, 45832.54167458416, 64231.0, 64652.70694586176, 64701.35298646953, 65204.02193967, 7490.700371985277, 7507.136679281734, 7564.357029652321, 7574.848503030019, 7850.489718168301, 11966.700832479257, 12067.215630983957, 12399.876001239872, 12402.627921162379, 12506.249625022529, 10203.795924081625, 10513.316634418738, 10826.13390928708, 10884.74825395058, 10982.780344392908, 8597.312215022657, 10209.591616335263, 10327.277090603586, 10463.372197668165, 10498.527566259689, 10219.642312519027, 10347.586096556355, 10422.270441069053, 10833.079188269085, 10878.129749619853, 7176.21061683204, 7320.26797320285, 7394.926050739424, 7552.962235188789, 7620.3522700571075, 9875.654352097643, 9941.900580994386, 10177.796444071226, 10222.591096356062, 10275.229357798045], "unit": "score", "name": "jquery.html", "value": 9404.568528828753}, {"lowerIsBetter": false, "replicates": [1452.7530319845625, 1596.6168119651284, 1636.4599682104904, 1917.2810196176433, 2039.5411032517686, 7977.042754869417, 8536.615852286646, 8692.348073894467, 8695.95652021739, 8710.04189539151, 8517.105703901094, 8764.91235087649, 8805.86791198131, 8811.95594022029, 8813.4711917285, 2091.9267825626107, 2161.9891900540474, 2166.967495487569, 2349.5888219561593, 2475.8762061896923, 2499.462615537659, 2507.5737124688753, 2512.1207577347873, 2514.9119780807673, 2517.811164162686, 2427.514497100578, 2480.9751902481016, 2496.500699860026, 2507.3856905058287, 2515.421453065796, 2451.595486744685, 2500.762427569378, 2502.1117503286255, 2502.4869901670113, 2505.4863752930787, 2463.051725085837, 2496.163785131983, 2503.7996960243318, 2507.69907611086, 2508.949821003588, 8732.650693972297, 8811.691590794291, 8812.691555795516, 8816.074312197192, 8823.0, 4705.835295764666, 5484.61607687462, 5601.467860553282, 5650.463205995424, 5661.0, 3593.7843729376323, 3740.5137332146646, 3769.453429252743, 3771.509703738524, 3781.0, 1003.1022235099618, 1016.2987538598344, 1033.3799720167915, 1049.8372752223418, 1080.8162612355918, 12034.81947770784, 12054.939725301318, 12056.397180141046, 12061.91442770155, 12070.879291207064, 4923.384576927884, 4936.136176169156, 5004.0, 5014.949850501448, 5024.869404384058, 10166.440845753406, 10173.694789156338, 10174.796504069876, 10185.0, 10188.337758045704, 5572.609917305831, 5583.246261754693, 5590.021746194399, 5590.440955904377, 5590.468905453975, 4476.104779044204, 4642.280446530793, 4647.604953578916, 4652.418447694038, 4852.2479015752615, 3966.900827479336, 4033.495813023372, 4092.0383709828166, 4106.507219133723, 4118.382242663624, 1298.9805152922713, 1337.1308649377982, 1341.7920222365549, 1351.6958684295955, 1360.4422186903323, 1378.0904602961998, 1398.1890503508143, 1427.4004917934492, 1427.4504315838476, 1429.428228708504, 8113.553754543556, 8371.874421883676, 8414.863993360941, 8421.4947103174, 8425.789355266168, 1197.694587880085, 1207.6497815633368, 1208.8791120888018, 1228.729679470515, 1229.8524177098805, 6324.936750632435, 6333.46165575922, 6406.167198264196, 6409.871802563923, 6413.551051426355, 10625.256232063835, 10916.290441121302, 10928.071113955402, 11030.669079927615, 11041.668749937515, 8501.574921253912, 10680.51937662807, 10743.355398675949, 10761.730956725987, 10914.345139291669, 8408.2432581068, 8453.239208471266, 8515.446495977742, 8518.168593752373, 8541.01778295504, 7328.96335518319, 7767.844643107219, 7935.40484463656, 7996.200379961957, 8009.43933924643, 9410.153086222273, 9689.273304502332, 9757.46333951611, 9819.16537094355, 9845.0, 9887.802243954937, 10088.899111008795, 10183.694489165337, 10189.898101019191, 10270.845937311096, 7155.356017958201, 7155.356017958201, 7206.2793720627515, 7235.565866047843, 7246.094238220223, 8361.372897032625, 8378.748637540884, 8437.198466145706, 8459.111793261618, 8466.322694184328, 7471.103467583925, 7586.582737949466, 7593.8860917087395, 7680.155182929985, 7793.142754297136, 7416.184219735933, 7416.332530072319, 7464.514806537558, 7577.393808495197, 7580.696772129053, 7881.251281128284, 7930.60346982637, 7962.6814927404575, 7969.362451004022, 7975.720849770462, 7058.54119482232, 7125.144982602121, 7129.536580122275, 7129.607871566906, 7218.422526197996, 7209.675564599711, 7242.565446073252, 7298.562086274628, 7311.013013243144, 7312.597807120659], "unit": "score", "name": "mootools.html", "value": 5022.957254964202}, {"lowerIsBetter": false, "replicates": [320.5304229304069, 322.7434189819095, 330.63134604915524, 332.3784522942098, 333.92152844081636, 6541.509386795991, 6588.769393071243, 6591.110200122982, 6600.339966003391, 6626.701798419071, 6483.837904052401, 6510.088587597728, 6530.020496925464, 6538.803835884931, 6544.280129185786, 4595.425571803525, 4687.671862969593, 4717.551832575899, 4797.808087676489, 4847.830325938584, 3961.8217180226957, 4073.7352072115364, 4698.436187657486, 4784.521547845223, 4820.963492849037, 4748.715077095368, 4751.738654374008, 4755.762211889409, 4759.238521836507, 4777.140114779339, 4558.977205113986, 4631.490536041033, 4724.574788269072, 4737.573618374328, 4743.8339658112145, 4040.2121586290687, 4041.373587094005, 4043.2519983802795, 4048.67610591152, 4048.8987775305854, 6184.412480814315, 6198.81403557894, 6214.471769899564, 6228.283747369065, 6234.438900498932, 5624.521915637176, 5633.57748168889, 5636.126400407943, 5640.069388550925, 5655.26481557399, 6624.401350396488, 6879.828004299932, 6889.655517224119, 6919.8962015569805, 6934.237233904267, 3713.9442908356395, 3985.043589538519, 4519.570640789119, 4609.446866376056, 4614.630829533629, 7245.311695388929, 7264.309890560441, 7274.490785645007, 7285.7814265572115, 7316.890246646304, 4655.441347038377, 4663.183942810063, 4666.370040044619, 4667.929981050287, 4668.789904454307, 4718.457377401574, 4723.858284251478, 4725.692829966041, 4727.385439892861, 4735.408073990751, 5609.831705048855, 5611.0, 5623.943760562423, 5707.172459993278, 5713.342965558931, 6638.900416493757, 6875.862482750417, 6880.965595171992, 6885.724571017103, 6990.755323563651, 1571.0966194438245, 1581.6520365519568, 1588.467863265819, 1593.6175317923614, 1598.1370060167612, 963.9710808675751, 993.9403635781877, 1014.7767491151935, 1015.583261350412, 1037.4605205293205, 6326.620402775756, 6378.075179099004, 6378.2346118466085, 6387.233531976192, 6394.0, 7186.892196617055, 7191.316824901626, 7194.460415468756, 7205.855882882418, 7233.674484648203, 6461.61230326182, 6488.480921526361, 6488.967555162289, 6489.902651460232, 6496.415322620986, 3081.691830816945, 3779.3197224499854, 3803.3914573668635, 3809.7714137151306, 3813.370793819044, 7272.818179545554, 7521.0974683038385, 7521.172671006185, 7533.058367704037, 7544.434167437465, 6540.953447448385, 7126.2161162272105, 7376.188619251878, 7441.218672039467, 7510.699572017058, 7620.618969051525, 7626.025849918537, 7628.0, 7631.122420921443, 7633.427492938163, 7014.368706816411, 7262.201157872735, 7277.599732014578, 7283.635818209174, 7301.561906285639, 5958.404159584007, 7185.353318201387, 7210.4231661468, 7225.783226503213, 7233.4574906883245, 7622.428317876071, 7642.617869106633, 7646.885296720665, 7679.424043196671, 7680.807979800326, 4538.88652783683, 4569.977150114228, 4585.862424127282, 4588.426446694163, 4590.380298659772, 6981.895271571032, 7288.416926645749, 7290.380317673057, 7303.123625165014, 7311.4516411268305, 6954.408875245661, 7001.614911179934, 7015.403690686348, 7017.964910175417, 7031.613261270679, 6967.96516017437, 6998.440124790106, 6999.965000174967, 7004.579725216503, 7006.369426751413, 6832.043513907957, 6834.419074378733, 6838.863222735418, 6839.384455399038, 6841.247462778991, 6676.732930682719, 6977.686004129725, 6996.860062798613, 7033.331833475811, 7033.437325013884, 5633.647924498173, 5962.612430192197, 6117.449429551361, 6557.901631475627, 6836.213835408992], "unit": "score", "name": "prototype.html", "value": 5061.786420745683}, {"lowerIsBetter": false, "replicates": [701.4984286235341, 702.192478649553, 728.2323993967776, 760.2473551184329, 777.5529070784297, 16356.446137616907, 17532.29870805168, 17860.464186074405, 17882.731759023623, 17912.283508659653, 14832.6291842704, 16460.024797024344, 16487.258073386696, 16523.00861948284, 16543.503694889143, 755.6561764397186, 765.5980610179645, 848.8455700247658, 887.2148148888248, 909.6452383570396, 935.8081593273363, 950.5959967014026, 951.0109486134412, 951.9000504946985, 953.4946478366443, 995.1044060345674, 1007.8840933292655, 1009.5860697114184, 1010.8079464901682, 1012.9139023183038, 773.9729109481169, 777.3819813248481, 967.3035414501547, 983.9950800246024, 997.0179373317277, 688.4939569416475, 729.489357449782, 735.2133217457323, 735.2867718313281, 735.9705611775522, 15688.921555392264, 15718.764218536731, 15734.68530629381, 15745.921270393575, 15747.212639368101, 12317.260964342167, 12346.197497162655, 12394.194377365442, 12566.874331256662, 12656.114072014963, 8282.958585207096, 10133.543990520444, 10150.441725705083, 10167.644132455402, 10422.687319380431, 3400.1159698478327, 3454.222799870034, 3506.386382383073, 3515.806630635314, 3700.3524383232825, 80877.0, 81330.78003829948, 81407.59296203482, 81573.59213203956, 81621.0, 9351.205147562398, 9391.765205869771, 9393.154616084585, 9606.711798646053, 9681.757956051155, 21708.13167473315, 21718.23986160477, 21725.348239552837, 21747.782522174577, 21754.89122554377, 7440.441966852508, 7561.054868141482, 7602.771916842504, 7668.65491052904, 7748.806279842936, 4004.4593979812935, 4071.572484889103, 4125.876223713293, 4151.750894946313, 4223.049813791934, 3601.981990090033, 3635.709143268532, 3647.0517665407187, 3652.6164752701116, 3662.5788034376387, 557.8744782424003, 652.663878102768, 655.0567183256096, 661.7882277671099, 679.1137565477001, 503.26381385159897, 504.97980080796356, 581.7265885034027, 611.0925275965188, 622.4864486798409, 7949.0, 7958.92554505146, 8264.752057438287, 8305.294050005696, 8353.916460835435, 576.9048107062372, 579.3771695427348, 607.9604825686405, 625.1154616218091, 625.1778910732372, 3688.0, 3805.82873770681, 3837.865674701372, 3844.884653460401, 3870.071182916136, 82970.58514707508, 85318.0, 89844.20311593862, 90200.54899725459, 90499.54750226339, 79758.0, 80515.59742201252, 80537.59731201307, 80644.0, 81293.0, 51120.744396278526, 69291.65354173197, 69393.65303173552, 69910.25224369533, 70491.00826429708, 6497.0, 7517.849643007218, 7793.844123117619, 7921.128675845537, 8078.959605201936, 47559.286610700176, 48559.0, 49920.25119623136, 50653.0, 51620.22569661383, 65820.0, 66605.66697166677, 67679.32320676927, 72352.0, 72558.0, 11215.71960700989, 11363.261388009752, 11373.31760094397, 11597.246178998676, 11865.0, 11918.106142039209, 11956.402179890796, 12056.879431205576, 12218.75562488763, 12312.26126432417, 9912.752181195528, 11558.133140014701, 11612.606487221587, 11998.0, 12025.278483291031, 11459.656210313704, 11611.187216894736, 11669.708257293636, 11731.941340293244, 11850.111241656738, 9336.486493242937, 11371.317720936438, 11418.257813242113, 11432.199746017699, 11895.226810257305, 9603.711888643353, 9763.658271960732, 9814.214862810815, 9902.801943961225, 10247.436390998566, 7764.301212890867, 9735.951320243354, 9819.803603928025, 9846.852297215692, 9908.950455247677], "unit": "score", "name": "yui.html", "value": 6964.430791213196}], "name": "dromaeo_css", "value": 7513.075510033864}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 108.0, 108.0, 110.0, 110.0, 112.0, 116.0, 111.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 110.6}, {"lowerIsBetter": true, "replicates": [152.0, 117.0, 146.0, 156.0, 116.0, 138.0, 137.0, 116.0, 152.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 134.8}, {"lowerIsBetter": true, "replicates": [182.0, 188.0, 187.0, 187.0, 187.0, 186.0, 187.0, 186.0, 181.0, 175.0], "unit": "score", "name": "audio-dft", "value": 184.6}, {"lowerIsBetter": true, "replicates": [76.0, 73.0, 88.0, 76.0, 56.0, 60.0, 64.0, 98.0, 101.0, 81.0], "unit": "score", "name": "audio-fft", "value": 77.3}, {"lowerIsBetter": true, "replicates": [92.0, 101.0, 127.0, 81.0, 78.0, 81.0, 118.0, 80.0, 80.0, 120.0], "unit": "score", "name": "audio-oscillator", "value": 95.8}, {"lowerIsBetter": true, "replicates": [97.0, 128.0, 116.0, 83.0, 96.0, 130.0, 89.0, 84.0, 87.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 99.3}, {"lowerIsBetter": true, "replicates": [94.0, 97.0, 92.0, 93.0, 95.0, 141.0, 148.0, 109.0, 93.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 105.5}, {"lowerIsBetter": true, "replicates": [87.0, 86.0, 87.0, 87.0, 86.0, 86.0, 87.0, 85.0, 87.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 86.2}, {"lowerIsBetter": true, "replicates": [57.0, 55.0, 54.0, 55.0, 56.0, 54.0, 55.0, 64.0, 56.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 56.0}, {"lowerIsBetter": true, "replicates": [75.0, 67.0, 75.0, 75.0, 73.0, 70.0, 67.0, 75.0, 92.0, 100.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 76.9}, {"lowerIsBetter": true, "replicates": [84.0, 81.0, 83.0, 82.0, 82.0, 80.0, 104.0, 81.0, 83.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.2}, {"lowerIsBetter": true, "replicates": [133.0, 130.0, 133.0, 129.0, 109.0, 123.0, 117.0, 130.0, 120.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.6}, {"lowerIsBetter": true, "replicates": [157.0, 175.0, 140.0, 139.0, 139.0, 168.0, 176.0, 135.0, 140.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 151.0}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 65.0, 68.0, 66.0, 69.0, 67.0, 66.0, 66.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.5}], "name": "kraken", "value": 1453.3}]} 17:03:38 INFO - Return code: 0 17:03:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:03:38 ERROR - # TBPL SUCCESS # 17:03:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:03:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:03:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:03:38 INFO - 2016-03-22 17:03:38.417 screenresolution[1684:9642] starting screenresolution argv=screenresolution get 17:03:38 INFO - 2016-03-22 17:03:38.420 screenresolution[1684:9642] Display 0: 1600x1200x32@60 17:03:38 INFO - 2016-03-22 17:03:38.427 screenresolution[1685:9645] starting screenresolution argv=screenresolution list 17:03:38 INFO - Available Modes on Display 0 17:03:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:03:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:03:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:03:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:03:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:03:38 INFO - Intel Iris: 17:03:38 INFO - Chipset Model: Intel Iris 17:03:38 INFO - Type: GPU 17:03:38 INFO - Bus: Built-In 17:03:38 INFO - VRAM (Dynamic, Max): 1536 MB 17:03:38 INFO - Vendor: Intel (0x8086) 17:03:38 INFO - Device ID: 0x0a2e 17:03:38 INFO - Revision ID: 0x0009 17:03:38 INFO - Displays: 17:03:38 INFO - Display: 17:03:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:03:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:03:38 INFO - Display Serial Number: 1600x1200 60 17:03:38 INFO - Main Display: Yes 17:03:38 INFO - Mirror: Off 17:03:38 INFO - Online: Yes 17:03:38 INFO - Rotation: Supported 17:03:38 INFO - Return code: 0 17:03:38 INFO - Running post-action listener: _resource_record_post_action 17:03:38 INFO - Running post-run listener: _resource_record_post_run 17:03:39 INFO - Total resource usage - Wall time: 1207s; CPU: 52.0%; Read bytes: 39269888; Write bytes: 703304704; Read time: 427; Write time: 1960 17:03:39 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389655040; Write bytes: 383297536; Read time: 29283; Write time: 728 17:03:39 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 38315520; Write bytes: 299355136; Read time: 413; Write time: 1152 17:03:39 INFO - Running post-run listener: _upload_blobber_files 17:03:39 INFO - Blob upload gear active. 17:03:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:03:39 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 17:03:39 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'] 17:03:39 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 17:03:40 INFO - (blobuploader) - INFO - Open directory for files ... 17:03:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:03:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:03:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:41 INFO - (blobuploader) - INFO - Done attempting. 17:03:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:03:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:03:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:42 INFO - (blobuploader) - INFO - Done attempting. 17:03:42 INFO - (blobuploader) - INFO - Iteration through files over. 17:03:42 INFO - Return code: 0 17:03:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:03:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:03:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9712c5e297a674a618c0b00fd808eb7705f96ba04652c1f901183605fb15f525b0b79f81a38cbf8b05487bb6e9a1e770c0f5d3950fbf13dbaa5ac83ee68eab61", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ca9c8c4a3a5fd912439647ea24792a7d67acf53759afe28d9f060039e214036663174c72def04662adcd7bf7566d7155f8846d733bcfe62bccade157162f272"} 17:03:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:03:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:03:42 INFO - Contents: 17:03:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9712c5e297a674a618c0b00fd808eb7705f96ba04652c1f901183605fb15f525b0b79f81a38cbf8b05487bb6e9a1e770c0f5d3950fbf13dbaa5ac83ee68eab61", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ca9c8c4a3a5fd912439647ea24792a7d67acf53759afe28d9f060039e214036663174c72def04662adcd7bf7566d7155f8846d733bcfe62bccade157162f272"} 17:03:42 INFO - Running post-run listener: copy_logs_to_upload_dir 17:03:42 INFO - Copying logs to upload dir... 17:03:42 INFO - mkdir: /builds/slave/test/build/upload/logs 17:03:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.867844 ========= master_lag: 43.38 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2016-03-22 17:04:26.035817) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 12 secs) (at 2016-03-22 17:04:26.038872) ========= 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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners TMPDIR=/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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/9712c5e297a674a618c0b00fd808eb7705f96ba04652c1f901183605fb15f525b0b79f81a38cbf8b05487bb6e9a1e770c0f5d3950fbf13dbaa5ac83ee68eab61", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ca9c8c4a3a5fd912439647ea24792a7d67acf53759afe28d9f060039e214036663174c72def04662adcd7bf7566d7155f8846d733bcfe62bccade157162f272"} build_url:https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/RiqL0MMbSPurMXZYHgcxNQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9712c5e297a674a618c0b00fd808eb7705f96ba04652c1f901183605fb15f525b0b79f81a38cbf8b05487bb6e9a1e770c0f5d3950fbf13dbaa5ac83ee68eab61", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ca9c8c4a3a5fd912439647ea24792a7d67acf53759afe28d9f060039e214036663174c72def04662adcd7bf7566d7155f8846d733bcfe62bccade157162f272"}' ========= master_lag: 12.87 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 12 secs) (at 2016-03-22 17:04:38.919980) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 16 secs) (at 2016-03-22 17:04:38.920294) ========= 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.8rVLKte3Fe/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.KcDLZwvkzp/Listeners TMPDIR=/var/folders/50/y6p535kd1q35zcnf_4b_jx_400000w/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.005109 ========= master_lag: 16.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 16 secs) (at 2016-03-22 17:04:54.933630) ========= ========= Started reboot skipped (results: 3, elapsed: 10 secs) (at 2016-03-22 17:04:54.933904) ========= ========= Finished reboot skipped (results: 3, elapsed: 10 secs) (at 2016-03-22 17:05:05.569714) ========= ========= Total master_lag: 72.43 =========