builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0198 starttime: 1455750087.37 results: success (0) buildid: 20160217131326 builduid: 606179c736844649b04b7da054b251dc revision: 8b188df8101e985e7c5f0dbdbdcdd78e0893a400 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.372289) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.372792) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.373116) ========= 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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.005604 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.396527) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.396868) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.415994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.416454) ========= 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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.005429 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.465625) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.465970) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.466385) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.466695) ========= 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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-02-17 15:01:27-- 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% 45.9M=0s 2016-02-17 15:01:27 (45.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.187705 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.670140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.670584) ========= 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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.025710 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:27.719884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-17 15:01:27.720410) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8b188df8101e985e7c5f0dbdbdcdd78e0893a400 --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 8b188df8101e985e7c5f0dbdbdcdd78e0893a400 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U82C2LdLZ8/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.DYpeWsr4Op/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-02-17 15:01:27,796 truncating revision to first 12 chars 2016-02-17 15:01:27,796 Setting DEBUG logging. 2016-02-17 15:01:27,796 attempt 1/10 2016-02-17 15:01:27,796 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8b188df8101e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-17 15:01:28,828 unpacking tar archive at: fx-team-8b188df8101e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.274724 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-17 15:01:29.012856) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:29.013264) ========= echo 8b188df8101e985e7c5f0dbdbdcdd78e0893a400 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'8b188df8101e985e7c5f0dbdbdcdd78e0893a400'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U82C2LdLZ8/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.DYpeWsr4Op/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 8b188df8101e985e7c5f0dbdbdcdd78e0893a400 program finished with exit code 0 elapsedTime=0.005537 script_repo_revision: '8b188df8101e985e7c5f0dbdbdcdd78e0893a400' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:29.065550) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:29.065910) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 15:01:29.079934) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-02-17 15:01:29.080341) ========= /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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:01:29 INFO - MultiFileLogger online at 20160217 15:01:29 in /builds/slave/test 15:01:29 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 15:01:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:01:29 INFO - {'append_to_log': False, 15:01:29 INFO - 'base_work_dir': '/builds/slave/test', 15:01:29 INFO - 'blob_upload_branch': 'Fx-Team', 15:01:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:01:29 INFO - 'branch': 'Fx-Team', 15:01:29 INFO - 'buildbot_json_path': 'buildprops.json', 15:01:29 INFO - 'config_files': ('talos/mac_config.py',), 15:01:29 INFO - 'default_actions': ('clobber', 15:01:29 INFO - 'read-buildbot-config', 15:01:29 INFO - 'download-and-extract', 15:01:29 INFO - 'populate-webroot', 15:01:29 INFO - 'create-virtualenv', 15:01:29 INFO - 'install', 15:01:29 INFO - 'run-tests'), 15:01:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:01:29 INFO - 'download_minidump_stackwalk': True, 15:01:29 INFO - 'download_symbols': 'ondemand', 15:01:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:01:29 INFO - 'tooltool.py': '/tools/tooltool.py', 15:01:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:01:29 INFO - '/tools/misc-python/virtualenv.py')}, 15:01:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:01:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:01:29 INFO - 'installer_path': 'installer.exe', 15:01:29 INFO - 'log_level': 'info', 15:01:29 INFO - 'log_name': 'talos', 15:01:29 INFO - 'log_to_console': True, 15:01:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:01:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:01:29 INFO - 'opt_config_files': (), 15:01:29 INFO - 'pip_index': False, 15:01:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:01:29 INFO - 'cmd': ('bash', 15:01:29 INFO - '-c', 15:01:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:01:29 INFO - 'enabled': True, 15:01:29 INFO - 'halt_on_failure': False, 15:01:29 INFO - 'name': 'check_screen_resolution'},), 15:01:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:01:29 INFO - 'cmd': ('bash', 15:01:29 INFO - '-c', 15:01:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:01:29 INFO - 'enabled': True, 15:01:29 INFO - 'halt_on_failure': False, 15:01:29 INFO - 'name': 'check_screen_resolution'},), 15:01:29 INFO - 'run_cmd_checks_enabled': True, 15:01:29 INFO - 'sps_profile': False, 15:01:29 INFO - 'sps_profile_interval': 0, 15:01:29 INFO - 'suite': 'dromaeojs', 15:01:29 INFO - 'system_bits': '32', 15:01:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:01:29 INFO - 'title': 't-yosemite-r7-0198', 15:01:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:01:29 INFO - 'use_talos_json': True, 15:01:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:01:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:01:29 INFO - 'work_dir': 'build'} 15:01:29 INFO - ##### 15:01:29 INFO - ##### Running clobber step. 15:01:29 INFO - ##### 15:01:29 INFO - Running pre-action listener: _resource_record_pre_action 15:01:29 INFO - Running main action method: clobber 15:01:29 INFO - rmtree: /builds/slave/test/build 15:01:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:01:30 INFO - Running post-action listener: _resource_record_post_action 15:01:30 INFO - ##### 15:01:30 INFO - ##### Running read-buildbot-config step. 15:01:30 INFO - ##### 15:01:30 INFO - Running pre-action listener: _resource_record_pre_action 15:01:30 INFO - Running main action method: read_buildbot_config 15:01:30 INFO - Using buildbot properties: 15:01:30 INFO - { 15:01:30 INFO - "project": "", 15:01:30 INFO - "product": "firefox", 15:01:30 INFO - "script_repo_revision": "production", 15:01:30 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:01:30 INFO - "repository": "", 15:01:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:01:30 INFO - "buildid": "20160217131326", 15:01:30 INFO - "pgo_build": "False", 15:01:30 INFO - "basedir": "/builds/slave/test", 15:01:30 INFO - "buildnumber": 457, 15:01:30 INFO - "slavename": "t-yosemite-r7-0198", 15:01:30 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:01:30 INFO - "platform": "yosemite_r7", 15:01:30 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:01:30 INFO - "branch": "fx-team", 15:01:30 INFO - "repo_path": "integration/fx-team", 15:01:30 INFO - "revision": "8b188df8101e985e7c5f0dbdbdcdd78e0893a400", 15:01:30 INFO - "stage_platform": "macosx64", 15:01:30 INFO - "builduid": "606179c736844649b04b7da054b251dc", 15:01:30 INFO - "slavebuilddir": "test" 15:01:30 INFO - } 15:01:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 15:01:30 INFO - Running post-action listener: _resource_record_post_action 15:01:30 INFO - ##### 15:01:30 INFO - ##### Running download-and-extract step. 15:01:30 INFO - ##### 15:01:30 INFO - Running pre-action listener: _resource_record_pre_action 15:01:30 INFO - Running main action method: download_and_extract 15:01:30 INFO - mkdir: /builds/slave/test/build/tests 15:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:30 INFO - https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 15:01:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/test_packages.json 15:01:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/test_packages.json 15:01:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 15:01:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 15:01:31 INFO - Downloaded 1277 bytes. 15:01:31 INFO - Reading from file /builds/slave/test/build/test_packages.json 15:01:31 INFO - Using the following test package requirements: 15:01:31 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 15:01:31 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 15:01:31 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 15:01:31 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 15:01:31 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 15:01:31 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 15:01:31 INFO - u'jsshell-mac.zip'], 15:01:31 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 15:01:31 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 15:01:31 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 15:01:31 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 15:01:31 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 15:01:31 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 15:01:31 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 15:01:31 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 15:01:31 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 15:01:31 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 15:01:31 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 15:01:31 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 15:01:31 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:31 INFO - https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:01:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 15:01:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 15:01:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 15:01:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:01:33 INFO - Downloaded 17806597 bytes. 15:01:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:01:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:01:36 INFO - Return code: 0 15:01:36 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:01:36 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')]} 15:01:36 INFO - https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:01:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 15:01:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 15:01:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 15:01:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:01:37 INFO - Downloaded 17806597 bytes. 15:01:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:01:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:01:41 INFO - Return code: 0 15:01:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:41 INFO - https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:01:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 15:01:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 15:01:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 15:01:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:01:42 INFO - Downloaded 10707071 bytes. 15:01:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:01:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:01:42 INFO - Return code: 0 15:01:42 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')]} 15:01:42 INFO - https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:01:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 15:01:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 15:01:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 15:01:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 15:01:45 INFO - Downloaded 111224853 bytes. 15:01:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 15:01:45 INFO - mkdir: /builds/slave/test/properties 15:01:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:01:45 INFO - Writing to file /builds/slave/test/properties/build_url 15:01:45 INFO - Contents: 15:01:45 INFO - build_url:https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 15:01:46 INFO - Running post-action listener: _resource_record_post_action 15:01:46 INFO - Running post-action listener: set_extra_try_arguments 15:01:46 INFO - ##### 15:01:46 INFO - ##### Running populate-webroot step. 15:01:46 INFO - ##### 15:01:46 INFO - Running pre-action listener: _resource_record_pre_action 15:01:46 INFO - Running main action method: populate_webroot 15:01:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:01:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:01:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:01:46 INFO - '--tppagecycles', 15:01:46 INFO - '7'], 15:01:46 INFO - 'tests': ['tsvgm']}}, 15:01:46 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:01:46 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:01:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:01:46 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 15:01:46 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:01:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:01:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:01:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:46 INFO - 'talos_options': ['--e10s'], 15:01:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:01:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:46 INFO - 'tests': ['damp', 'tps']}, 15:01:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:46 INFO - 'talos_options': ['--e10s'], 15:01:46 INFO - 'tests': ['damp', 'tps']}, 15:01:46 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:01:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:01:46 INFO - 'other': {'tests': ['a11yr', 15:01:46 INFO - 'ts_paint', 15:01:46 INFO - 'tpaint', 15:01:46 INFO - 'sessionrestore', 15:01:46 INFO - 'sessionrestore_no_auto_restore']}, 15:01:46 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:01:46 INFO - 'tests': ['a11yr', 15:01:46 INFO - 'ts_paint', 15:01:46 INFO - 'tpaint', 15:01:46 INFO - 'sessionrestore', 15:01:46 INFO - 'sessionrestore_no_auto_restore']}, 15:01:46 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:01:46 INFO - 'tests': ['a11yr', 15:01:46 INFO - 'ts_paint', 15:01:46 INFO - 'tpaint', 15:01:46 INFO - 'sessionrestore', 15:01:46 INFO - 'sessionrestore_no_auto_restore']}, 15:01:46 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:01:46 INFO - 'tests': ['a11yr', 15:01:46 INFO - 'ts_paint', 15:01:46 INFO - 'tpaint', 15:01:46 INFO - 'sessionrestore', 15:01:46 INFO - 'sessionrestore_no_auto_restore']}, 15:01:46 INFO - 'other_l64': {'tests': ['a11yr', 15:01:46 INFO - 'ts_paint', 15:01:46 INFO - 'tpaint', 15:01:46 INFO - 'sessionrestore', 15:01:46 INFO - 'sessionrestore_no_auto_restore']}, 15:01:46 INFO - 'other_nol64': {'tests': ['a11yr', 15:01:46 INFO - 'ts_paint', 15:01:46 INFO - 'tpaint', 15:01:46 INFO - 'sessionrestore', 15:01:46 INFO - 'sessionrestore_no_auto_restore']}, 15:01:46 INFO - 'svgr': {'tests': ['tsvgx', 15:01:46 INFO - 'tsvgr_opacity', 15:01:46 INFO - 'tart', 15:01:46 INFO - 'tscrollx', 15:01:46 INFO - 'cart']}, 15:01:46 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:01:46 INFO - 'tests': ['tsvgx', 15:01:46 INFO - 'tsvgr_opacity', 15:01:46 INFO - 'tart', 15:01:46 INFO - 'tscrollx', 15:01:46 INFO - 'cart']}, 15:01:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:46 INFO - 'tests': ['tp5o']}, 15:01:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:46 INFO - 'talos_options': ['--e10s'], 15:01:46 INFO - 'tests': ['tp5o']}, 15:01:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:46 INFO - 'talos_options': ['--xperf_path', 15:01:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:01:46 INFO - 'tests': ['tp5n']}, 15:01:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:46 INFO - 'talos_options': ['--e10s', 15:01:46 INFO - '--xperf_path', 15:01:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:01:46 INFO - 'tests': ['tp5n']}}, 15:01:46 INFO - 'talos.zip': {'path': '', 15:01:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:01:46 INFO - Running post-action listener: _resource_record_post_action 15:01:46 INFO - ##### 15:01:46 INFO - ##### Running create-virtualenv step. 15:01:46 INFO - ##### 15:01:46 INFO - Running pre-action listener: _resource_record_pre_action 15:01:46 INFO - Running main action method: create_virtualenv 15:01:46 INFO - Creating virtualenv /builds/slave/test/build/venv 15:01:46 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 15:01:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:01:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:01:46 INFO - Using real prefix '/tools/python27' 15:01:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:01:47 INFO - Installing distribute.............................................................................................................................................................................................done. 15:01:50 INFO - Installing pip.................done. 15:01:50 INFO - Return code: 0 15:01:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:50 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 0x105feca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ff44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf73cba500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, '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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:01:50 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 15:01:50 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 15:01:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:01:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:50 INFO - 'HOME': '/Users/cltbld', 15:01:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:50 INFO - 'LOGNAME': 'cltbld', 15:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:50 INFO - 'MOZ_NO_REMOTE': '1', 15:01:50 INFO - 'NO_EM_RESTART': '1', 15:01:50 INFO - 'PAGER': '/bin/cat', 15:01:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:50 INFO - 'PWD': '/builds/slave/test', 15:01:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:50 INFO - 'SHELL': '/bin/bash', 15:01:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:01:50 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:01:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:50 INFO - 'USER': 'cltbld', 15:01:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:50 INFO - 'XPC_FLAGS': '0x0', 15:01:50 INFO - 'XPC_SERVICE_NAME': '0', 15:01:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:50 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 15:01:50 INFO - Cleaning up... 15:01:50 INFO - Return code: 0 15:01:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:50 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 0x105feca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ff44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf73cba500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, '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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:01:50 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 15:01:50 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 15:01:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:01:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:50 INFO - 'HOME': '/Users/cltbld', 15:01:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:50 INFO - 'LOGNAME': 'cltbld', 15:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:50 INFO - 'MOZ_NO_REMOTE': '1', 15:01:50 INFO - 'NO_EM_RESTART': '1', 15:01:50 INFO - 'PAGER': '/bin/cat', 15:01:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:50 INFO - 'PWD': '/builds/slave/test', 15:01:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:50 INFO - 'SHELL': '/bin/bash', 15:01:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:01:50 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:01:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:50 INFO - 'USER': 'cltbld', 15:01:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:50 INFO - 'XPC_FLAGS': '0x0', 15:01:50 INFO - 'XPC_SERVICE_NAME': '0', 15:01:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:50 INFO - Downloading/unpacking psutil>=0.7.1 15:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:01:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:01:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:01:53 INFO - Installing collected packages: psutil 15:01:53 INFO - Running setup.py install for psutil 15:01:53 INFO - building 'psutil._psutil_osx' extension 15:01:53 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 15:01:54 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 15:01:54 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 15:01:54 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 15:01:54 INFO - building 'psutil._psutil_posix' extension 15:01:54 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 15:01:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:01:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:01:54 INFO - ^ 15:01:54 INFO - 1 warning generated. 15:01:54 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 15:01:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:01:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:01:54 INFO - Successfully installed psutil 15:01:54 INFO - Cleaning up... 15:01:54 INFO - Return code: 0 15:01:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:01:54 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')]} 15:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:54 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')]} 15:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:54 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 0x105feca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ff44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf73cba500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, '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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:01:54 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 15:01:54 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 15:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:54 INFO - 'HOME': '/Users/cltbld', 15:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:54 INFO - 'LOGNAME': 'cltbld', 15:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:54 INFO - 'MOZ_NO_REMOTE': '1', 15:01:54 INFO - 'NO_EM_RESTART': '1', 15:01:54 INFO - 'PAGER': '/bin/cat', 15:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:54 INFO - 'PWD': '/builds/slave/test', 15:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:54 INFO - 'SHELL': '/bin/bash', 15:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:01:54 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:54 INFO - 'USER': 'cltbld', 15:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:54 INFO - 'XPC_FLAGS': '0x0', 15:01:54 INFO - 'XPC_SERVICE_NAME': '0', 15:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:01:54 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 15:01:54 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 15:01:55 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 15:01:55 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 15:01:55 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 15:01:55 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 15:01:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:01:57 INFO - Installing collected packages: mozsystemmonitor 15:01:57 INFO - Running setup.py install for mozsystemmonitor 15:01:57 INFO - Successfully installed mozsystemmonitor 15:01:57 INFO - Cleaning up... 15:01:57 INFO - Return code: 0 15:01:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:57 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 0x105feca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ff44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf73cba500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, '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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:01:57 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 15:01:57 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 15:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:57 INFO - 'HOME': '/Users/cltbld', 15:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:57 INFO - 'LOGNAME': 'cltbld', 15:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:57 INFO - 'MOZ_NO_REMOTE': '1', 15:01:57 INFO - 'NO_EM_RESTART': '1', 15:01:57 INFO - 'PAGER': '/bin/cat', 15:01:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:57 INFO - 'PWD': '/builds/slave/test', 15:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:57 INFO - 'SHELL': '/bin/bash', 15:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:01:57 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:57 INFO - 'USER': 'cltbld', 15:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:57 INFO - 'XPC_FLAGS': '0x0', 15:01:57 INFO - 'XPC_SERVICE_NAME': '0', 15:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:57 INFO - Downloading/unpacking blobuploader==1.2.4 15:01:57 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 15:01:57 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 15:01:57 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 15:01:57 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 15:01:57 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 15:01:57 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 15:02:00 INFO - Downloading blobuploader-1.2.4.tar.gz 15:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:02:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:02:00 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 15:02:00 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 15:02:00 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 15:02:00 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 15:02:00 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 15:02:00 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 15:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:02:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:02:00 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 15:02:00 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 15:02:00 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 15:02:00 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 15:02:00 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 15:02:00 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 15:02:01 INFO - Downloading docopt-0.6.1.tar.gz 15:02:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:02:01 INFO - Installing collected packages: blobuploader, requests, docopt 15:02:01 INFO - Running setup.py install for blobuploader 15:02:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:02:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:02:01 INFO - Running setup.py install for requests 15:02:02 INFO - Running setup.py install for docopt 15:02:02 INFO - Successfully installed blobuploader requests docopt 15:02:02 INFO - Cleaning up... 15:02:02 INFO - Return code: 0 15:02:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:02 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 0x105feca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ff44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf73cba500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, '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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:02:02 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 15:02:02 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 15:02:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:02:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:02 INFO - 'HOME': '/Users/cltbld', 15:02:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:02 INFO - 'LOGNAME': 'cltbld', 15:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:02 INFO - 'MOZ_NO_REMOTE': '1', 15:02:02 INFO - 'NO_EM_RESTART': '1', 15:02:02 INFO - 'PAGER': '/bin/cat', 15:02:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:02 INFO - 'PWD': '/builds/slave/test', 15:02:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:02 INFO - 'SHELL': '/bin/bash', 15:02:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:02:02 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:02:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:02 INFO - 'USER': 'cltbld', 15:02:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:02 INFO - 'XPC_FLAGS': '0x0', 15:02:02 INFO - 'XPC_SERVICE_NAME': '0', 15:02:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-s21nE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-PAhTlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-QykDS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-yCgGG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:02 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-NUHQlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-PRtcs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-fKWO9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-wFJP9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-YF2jdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-rcqeaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-M8ER2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ugs85Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-_BtFO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-lGQFrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-JmFbch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ADn8iS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-hJJ9WV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:02:04 INFO - Running setup.py install for manifestparser 15:02:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:02:04 INFO - Running setup.py install for mozcrash 15:02:04 INFO - Running setup.py install for mozdebug 15:02:04 INFO - Running setup.py install for mozdevice 15:02:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:02:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:02:04 INFO - Running setup.py install for mozfile 15:02:05 INFO - Running setup.py install for mozhttpd 15:02:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:02:05 INFO - Running setup.py install for mozinfo 15:02:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:02:05 INFO - Running setup.py install for mozInstall 15:02:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:02:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:02:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:02:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:02:05 INFO - Running setup.py install for mozleak 15:02:05 INFO - Running setup.py install for mozlog 15:02:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:02:05 INFO - Running setup.py install for moznetwork 15:02:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:02:05 INFO - Running setup.py install for mozprocess 15:02:06 INFO - Running setup.py install for mozprofile 15:02:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:02:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:02:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:02:06 INFO - Running setup.py install for mozrunner 15:02:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:02:06 INFO - Running setup.py install for mozscreenshot 15:02:06 INFO - Running setup.py install for moztest 15:02:06 INFO - Running setup.py install for mozversion 15:02:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:02:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:02:06 INFO - Cleaning up... 15:02:06 INFO - Return code: 0 15:02:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:06 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')]} 15:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:06 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')]} 15:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:06 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 0x105feca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ff44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf73cba500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, '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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:02:06 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 15:02:06 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 15:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:06 INFO - 'HOME': '/Users/cltbld', 15:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:06 INFO - 'LOGNAME': 'cltbld', 15:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:06 INFO - 'MOZ_NO_REMOTE': '1', 15:02:06 INFO - 'NO_EM_RESTART': '1', 15:02:06 INFO - 'PAGER': '/bin/cat', 15:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:06 INFO - 'PWD': '/builds/slave/test', 15:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:06 INFO - 'SHELL': '/bin/bash', 15:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:02:06 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:06 INFO - 'USER': 'cltbld', 15:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:06 INFO - 'XPC_FLAGS': '0x0', 15:02:06 INFO - 'XPC_SERVICE_NAME': '0', 15:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:02:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-EbEAe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:02:07 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)) 15:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:02:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-2XThHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:02:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:02:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-YczTcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:02:07 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)) 15:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:02:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-9ah1MV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:02:07 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)) 15:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:02:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-5OHTpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:02:07 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)) 15:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:02:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-zgzq7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:02:07 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)) 15:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:02:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-C2s0QE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:02:07 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)) 15:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:02:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-iN8OWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:02:07 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)) 15:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:02:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-8uKA_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:02:08 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)) 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:02:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-NzMXvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:02:08 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)) 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:02:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-RnVelo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:02:08 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)) 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:02:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Pjnanu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:02:08 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)) 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:02:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-kz6B3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:02:08 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)) 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:02:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-A7NTaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:02:08 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)) 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-2eRyf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:02:08 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)) 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:02:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-KAjspw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:02:08 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)) 15:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:02:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-4Gz1G7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:02:08 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)) 15:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:02:08 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)) 15:02:08 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)) 15:02:08 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)) 15:02:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02: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 15:02:11 INFO - Downloading blessings-1.6.tar.gz 15:02:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:02:11 INFO - Installing collected packages: blessings 15:02:11 INFO - Running setup.py install for blessings 15:02:11 INFO - Successfully installed blessings 15:02:11 INFO - Cleaning up... 15:02:11 INFO - Return code: 0 15:02:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:02:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:11 INFO - Reading from file tmpfile_stdout 15:02:11 INFO - Current package versions: 15:02:11 INFO - blessings == 1.6 15:02:11 INFO - blobuploader == 1.2.4 15:02:11 INFO - docopt == 0.6.1 15:02:11 INFO - manifestparser == 1.1 15:02:11 INFO - mozInstall == 1.12 15:02:11 INFO - mozcrash == 0.16 15:02:11 INFO - mozdebug == 0.1 15:02:11 INFO - mozdevice == 0.48 15:02:11 INFO - mozfile == 1.2 15:02:11 INFO - mozhttpd == 0.7 15:02:11 INFO - mozinfo == 0.9 15:02:11 INFO - mozleak == 0.1 15:02:11 INFO - mozlog == 3.1 15:02:11 INFO - moznetwork == 0.27 15:02:11 INFO - mozprocess == 0.22 15:02:11 INFO - mozprofile == 0.28 15:02:11 INFO - mozrunner == 6.11 15:02:11 INFO - mozscreenshot == 0.1 15:02:11 INFO - mozsystemmonitor == 0.0 15:02:11 INFO - moztest == 0.7 15:02:11 INFO - mozversion == 1.4 15:02:11 INFO - psutil == 3.1.1 15:02:11 INFO - requests == 1.2.3 15:02:11 INFO - wsgiref == 0.1.2 15:02:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:11 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')]} 15:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:11 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')]} 15:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:11 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 0x105feca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ff44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf73cba500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, '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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:02:11 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 15:02:11 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 15:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:11 INFO - 'HOME': '/Users/cltbld', 15:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:11 INFO - 'LOGNAME': 'cltbld', 15:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:11 INFO - 'MOZ_NO_REMOTE': '1', 15:02:11 INFO - 'NO_EM_RESTART': '1', 15:02:11 INFO - 'PAGER': '/bin/cat', 15:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:11 INFO - 'PWD': '/builds/slave/test', 15:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:11 INFO - 'SHELL': '/bin/bash', 15:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:02:11 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:11 INFO - 'USER': 'cltbld', 15:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:11 INFO - 'XPC_FLAGS': '0x0', 15:02:11 INFO - 'XPC_SERVICE_NAME': '0', 15:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 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)) 15:02:12 INFO - Cleaning up... 15:02:12 INFO - Return code: 0 15:02:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:02:12 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')]} 15:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:12 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')]} 15:02:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:12 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 0x105feca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ff44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf73cba500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, '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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:02:12 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 15:02:12 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 15:02:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:02:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:12 INFO - 'HOME': '/Users/cltbld', 15:02:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:12 INFO - 'LOGNAME': 'cltbld', 15:02:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:12 INFO - 'MOZ_NO_REMOTE': '1', 15:02:12 INFO - 'NO_EM_RESTART': '1', 15:02:12 INFO - 'PAGER': '/bin/cat', 15:02:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:12 INFO - 'PWD': '/builds/slave/test', 15:02:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:12 INFO - 'SHELL': '/bin/bash', 15:02:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:02:12 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:02:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:12 INFO - 'USER': 'cltbld', 15:02:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:12 INFO - 'XPC_FLAGS': '0x0', 15:02:12 INFO - 'XPC_SERVICE_NAME': '0', 15:02:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:12 INFO - Downloading/unpacking jsonschema 15:02:12 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 15:02:12 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 15:02:12 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 15:02:12 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 15:02:12 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 15:02:12 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 15:02:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:02:14 INFO - Downloading/unpacking functools32 (from jsonschema) 15:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:02:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:02:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:02:15 INFO - Downloading functools32-3.2.3-2.tar.gz 15:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:02:15 INFO - warning: no files found matching '*.txt' 15:02:15 INFO - no previously-included directories found matching 'build' 15:02:15 INFO - no previously-included directories found matching 'dist' 15:02:15 INFO - no previously-included directories found matching '.git*' 15:02:15 INFO - Installing collected packages: jsonschema, functools32 15:02:15 INFO - Running setup.py install for functools32 15:02:15 INFO - warning: no files found matching '*.txt' 15:02:15 INFO - no previously-included directories found matching 'build' 15:02:15 INFO - no previously-included directories found matching 'dist' 15:02:15 INFO - no previously-included directories found matching '.git*' 15:02:15 INFO - Successfully installed jsonschema functools32 15:02:15 INFO - Cleaning up... 15:02:15 INFO - Return code: 0 15:02:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105feca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ff44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf73cba500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, '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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 15:02:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:15 INFO - 'HOME': '/Users/cltbld', 15:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:15 INFO - 'LOGNAME': 'cltbld', 15:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:15 INFO - 'MOZ_NO_REMOTE': '1', 15:02:15 INFO - 'NO_EM_RESTART': '1', 15:02:15 INFO - 'PAGER': '/bin/cat', 15:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:15 INFO - 'PWD': '/builds/slave/test', 15:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:15 INFO - 'SHELL': '/bin/bash', 15:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:02:15 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:15 INFO - 'USER': 'cltbld', 15:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:15 INFO - 'XPC_FLAGS': '0x0', 15:02:15 INFO - 'XPC_SERVICE_NAME': '0', 15:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:15 INFO - Downloading/unpacking flake8 15:02:15 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 15:02:15 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 15:02:15 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 15:02:15 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 15:02:15 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 15:02:15 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 15:02:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:02:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:02:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 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 15:02:18 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 15:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:02:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:02:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:02:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:02:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:02:19 INFO - no previously-included directories found matching 'docs/_build' 15:02:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:02:19 INFO - Running setup.py install for pep8 15:02:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:02:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:02:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:02:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:02:19 INFO - no previously-included directories found matching 'docs/_build' 15:02:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:02:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:02:19 INFO - Cleaning up... 15:02:19 INFO - Return code: 0 15:02:19 INFO - Running post-action listener: _resource_record_post_action 15:02:19 INFO - Running post-action listener: _start_resource_monitoring 15:02:19 INFO - Starting resource monitoring. 15:02:19 INFO - ##### 15:02:19 INFO - ##### Running install step. 15:02:19 INFO - ##### 15:02:19 INFO - Running pre-action listener: _resource_record_pre_action 15:02:19 INFO - Running main action method: install 15:02:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:20 INFO - Reading from file tmpfile_stdout 15:02:20 INFO - Detecting whether we're running mozinstall >=1.0... 15:02:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:02:20 INFO - Reading from file tmpfile_stdout 15:02:20 INFO - Output received: 15:02:20 INFO - Usage: mozinstall [options] installer 15:02:20 INFO - Options: 15:02:20 INFO - -h, --help show this help message and exit 15:02:20 INFO - -d DEST, --destination=DEST 15:02:20 INFO - Directory to install application into. [default: 15:02:20 INFO - "/builds/slave/test"] 15:02:20 INFO - --app=APP Application being installed. [default: firefox] 15:02:20 INFO - mkdir: /builds/slave/test/build/application 15:02:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:02:53 INFO - Reading from file tmpfile_stdout 15:02:53 INFO - Output received: 15:02:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:02:53 INFO - Running post-action listener: _resource_record_post_action 15:02:53 INFO - ##### 15:02:53 INFO - ##### Running run-tests step. 15:02:53 INFO - ##### 15:02:53 INFO - Running pre-action listener: _resource_record_pre_action 15:02:53 INFO - Running main action method: run_tests 15:02:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:02:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:02:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:02:53 INFO - 2016-02-17 15:02:53.879 screenresolution[1654:7461] starting screenresolution argv=screenresolution get 15:02:53 INFO - 2016-02-17 15:02:53.881 screenresolution[1654:7461] Display 0: 1600x1200x32@60 15:02:53 INFO - 2016-02-17 15:02:53.887 screenresolution[1655:7463] starting screenresolution argv=screenresolution list 15:02:53 INFO - Available Modes on Display 0 15:02:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:02:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:02:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:02:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:02:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:02:54 INFO - Intel Iris: 15:02:54 INFO - Chipset Model: Intel Iris 15:02:54 INFO - Type: GPU 15:02:54 INFO - Bus: Built-In 15:02:54 INFO - VRAM (Dynamic, Max): 1536 MB 15:02:54 INFO - Vendor: Intel (0x8086) 15:02:54 INFO - Device ID: 0x0a2e 15:02:54 INFO - Revision ID: 0x0009 15:02:54 INFO - Displays: 15:02:54 INFO - Display: 15:02:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:02:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:02:54 INFO - Display Serial Number: 1600x1200 60 15:02:54 INFO - Main Display: Yes 15:02:54 INFO - Mirror: Off 15:02:54 INFO - Online: Yes 15:02:54 INFO - Rotation: Supported 15:02:54 INFO - Return code: 0 15:02:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:02:54 INFO - Python 2.7.3 15:02:54 INFO - Return code: 0 15:02:54 INFO - grabbing minidump binary from tooltool 15:02:54 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')]} 15:02:54 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 0x105e90d30>, '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 0x105cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e87b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:02:54 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 15:02:54 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 15:02:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:02:54 INFO - Return code: 0 15:02:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:02:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:02:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:02:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:02:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:02:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:02:55 INFO - Return code: 0 15:02:55 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/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 15:02:55 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/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0198 --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 15:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U82C2LdLZ8/Render', 15:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:55 INFO - 'HOME': '/Users/cltbld', 15:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:55 INFO - 'LOGNAME': 'cltbld', 15:02:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:02:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:55 INFO - 'MOZ_NO_REMOTE': '1', 15:02:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:02:55 INFO - 'NO_EM_RESTART': '1', 15:02:55 INFO - 'PAGER': '/bin/cat', 15:02:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:55 INFO - 'PWD': '/builds/slave/test', 15:02:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:55 INFO - 'SHELL': '/bin/bash', 15:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYpeWsr4Op/Listeners', 15:02:55 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 15:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:55 INFO - 'USER': 'cltbld', 15:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:55 INFO - 'XPC_FLAGS': '0x0', 15:02:55 INFO - 'XPC_SERVICE_NAME': '0', 15:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:55 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/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 15:02:55 INFO - mozversion application_buildid: 20160217131326 15:02:55 INFO - mozversion application_changeset: 8b188df8101e985e7c5f0dbdbdcdd78e0893a400 15:02:55 INFO - mozversion application_display_name: Nightly 15:02:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:02:55 INFO - mozversion application_name: Firefox 15:02:55 INFO - mozversion application_remotingname: firefox 15:02:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:02:55 INFO - mozversion application_vendor: Mozilla 15:02:55 INFO - mozversion application_version: 47.0a1 15:02:55 INFO - mozversion platform_buildid: 20160217131326 15:02:55 INFO - mozversion platform_changeset: 8b188df8101e985e7c5f0dbdbdcdd78e0893a400 15:02:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:02:55 INFO - mozversion platform_version: 47.0a1 15:02:55 INFO - using testdate: 1455750175 15:02:55 INFO - actual date: 1455750175 15:02:56 INFO - starting webserver on 'localhost:49279' 15:02:56 INFO - SUITE-START | Running 2 tests 15:02:56 INFO - TEST-START | dromaeo_css 15:02:56 INFO - operating with platform_type : mac_ 15:02:56 INFO - Initialising browser for dromaeo_css test... 15:02:56 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmp7XNXl9/profile http://localhost:49279/getInfo.html) 15:02:58 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 15:02:58 INFO - PROCESS | 1673 | colorDepth:24 15:02:58 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 15:02:58 INFO - PROCESS | 1673 | __metrics 15:02:58 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:02:58 INFO - PROCESS | 1673 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:02:59 INFO - TEST-INFO | 1673: exit 0 15:02:59 INFO - Browser initialized. 15:02:59 INFO - Running cycle 1/1 for dromaeo_css test... 15:02:59 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmp7XNXl9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:03:11 INFO - PROCESS | 1676 | RSS: Main: 244166656 15:03:11 INFO - PROCESS | 1676 | 15:04:02 INFO - PROCESS | 1676 | 1455750242193 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] 15:06:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 15:06:14 INFO - PROCESS | 1676 | RSS: Main: 418123776 15:06:14 INFO - PROCESS | 1676 | 15:09:16 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 15:09:17 INFO - PROCESS | 1676 | RSS: Main: 382078976 15:09:17 INFO - PROCESS | 1676 | 15:12:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 15:12:19 INFO - PROCESS | 1676 | RSS: Main: 380051456 15:12:19 INFO - PROCESS | 1676 | 15:15:22 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 15:15:22 INFO - PROCESS | 1676 | RSS: Main: 338300928 15:15:22 INFO - PROCESS | 1676 | 15:18:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 15:18:26 INFO - PROCESS | 1676 | RSS: Main: 342106112 15:18:26 INFO - PROCESS | 1676 | 15:21:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 15:21:29 INFO - PROCESS | 1676 | RSS: Main: 384540672 15:21:29 INFO - PROCESS | 1676 | 15:21:29 INFO - PROCESS | 1676 | __start_tp_report 15:21:29 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 15:21:29 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 15:21:29 INFO - PROCESS | 1676 | |i|pagename|runs| 15:21:29 INFO - PROCESS | 1676 | |0;dojo.html;3309.9338013239735;3365.276465559905;3443.3285509325674;3452.205992621697;3486.755927085102;5560.916586251209;5580.748866301016;5609.3268807743025;5621.77512899484;5633.718314084294;5616.943830561693;5629.465200805927;5649.689267090308;5750.33871104822;5763.366029736726;5036.294918711365;5081.237814327844;5098.4136824265315;5105.463926287742;5194.71429071401;5176.482351764831;5179.766910489018;5200.843974680765;5225.686458812484;5256.526912577867;5053.620978426614;5060.544550990428;5142.820001299937;5152.871178220537;5185.455527169631;5127.66670166438;5151.304573882516;5151.922721159186;5153.948460515422;5178.508041736029;5063.341765570489;5087.313212716273;5103.260027296022;5119.10415677255;5134.691918484903;4668.836590719308;5413.890152518724;5568.081266591008;5571.0807716726695;5670.829875103754;6197.845053873644;6207.96896015517;6211;6215.751369945242;6228.314885362606;6214.502839772853;6241.375862413768;6252.6561039142825;6259.155014073087;6261.186045814015;5629.549636029149;5679.5172410345585;5716.314042314907;5718.142278658193;5719.542436605062;6302.810915672537;6324.462420694293;6324.810255692337;6334.778282760081;6334.873302533923;5999.250093738283;6010.3689112643415;6066.332703402622;6089.056196289582;6099.817005489843;6059.969700151559;6107.552954153613;6110.194261626863;6155.384461553898;6224.470919971853;6120.142398366865;6149.538784591174;6171.691415429211;6175.660338681326;6187.257529096447;6165.32181460039;6185.103160041769;6191.102290167991;6195.938040619626;6199;4795.544423279783;4840.782164802592;4861.732604706775;4866.853994380174;4897.510248975145;4049.7445791804635;4189.141226048653;4337.327714204303;4435.556444355603;4444.844430444984;5620.859478512988;5694.829155125353;5703.686297253691;5704;5722.54219662426;6185.288691800501;6188.876222475616;6220.720067596879;6223.906641400383;6225.533085018551;4753.572178503882;5594.1888426177975;5694.4590263924865;5701.315842098892;5710.71446427677;5329.520343169133;5337.985782701275;5376.946230537723;5379.515843574019;5393.9730301348245;6265.185525881606;6274.2784579773;6283;6300;6302.243730752431;6120.7551697931585;6231.22109736283;6240.939040363059;6242.250929888442;6251.4061164189325;6254.186955695731;6287.245530536365;6305.369463053841;6314.273858506331;6316.368363163647;6090.3605121461605;6115.969420152871;6117.357677443804;6122.571420000557;6132.877342453036;5880.94119058804;6158.969205153946;6209.285932117684;6213.254409470893;6221.3156552778255;6021.819345419644;6068.575199736153;6086.2087928569;6106.885399019288;6125.509959203342;6020.729067191899;6067.575269731076;6075.392460753889;6078.54410919174;6089.086637004485;5737.110747834093;5851.912221316598;5854.092615644582;5908.497777688773;5910.881782364415;5868.295804503487;5920.970395147997;5955.970220148872;5963.582549221686;5986.281646202309;5791.102379131242;5852.151438041375;5868.119782032559;5870.383609721089;5873.706314684334 15:21:29 INFO - PROCESS | 1676 | |1;ext.html;458.68580022684455;475.9505290833712;495.093978020223;526.2185654303358;579.5016285994042;26106;30273.697263027363;30399.240018999535;36829.263414731686;37094.07264818381;33670.49494257582;40931.18137637246;41295;41329;41369;9397.765055873606;9629.037096290385;9630.566624501915;9634.807303853919;9659.613615455408;9439.433633981984;9582.568784404719;9583.472908990003;9590.376625519353;9591.184749296319;8352.498850068985;9787.804243915152;9797.853032204523;9804.166645835112;9811.852822207637;9677.806443871119;9747.610095596168;9759.316847820655;9776.90223097767;9777.608895644165;9614.326997110205;9761.463119528424;9763.853542196874;9764.707058788248;9770.218382529381;40282.19435611301;40637.79681101605;40638.59361406378;40646.577369791914;40721.70617819694;3063.5251536011733;3201.679832016803;3491.703205227533;3741.681957033655;3853.9401664542474;28888.566671499946;29016;29821;29830.85084574585;29886;2736.165469531792;2762.185155379168;2833.3058400691757;2849.843258620775;2850.5439129739143;477964;478583;478954.6052269751;479706;480463;64086.35913640897;64091.67954160293;64492.355076449574;64583.354166458674;64659.67670161619;67959.3204067963;67988;68128.31871681318;68136;68345;16419.917900410423;16539.007659540464;16546.33814647425;16566.337346506247;16594;12559.372031398394;12620.81068783969;12641.620751377473;12644.557440489538;12649.936750316374;16166.434174803826;19778.318842898516;19869.105890234972;19894.502637433892;19900.90049549772;8470.788230294169;8585.399022068521;8684;8733.38866279367;8803.207711306013;805.3476683886071;921.2906062331967;977.4819345746765;988.5699720621552;1031.1544533482622;26226.344341391617;26402.86798565995;30007;30546.38907221843;30705.846470767505;865.5801936061006;950.3395140377371;1042.4266653340633;1054.1891063804444;1165.9417029148678;15547.533573992798;15576.92211538935;15619.297131629104;15694.843051569338;15841;526995;537696.3115184399;538060;540292;543051;57577.71211143918;57979.42020579825;58107.2567822966;58249.417505825244;58270.04704600072;1214.107630891294;1266.9429875655617;1407.3877863129571;1419.8722115009286;1426.686129051607;26193.780058986427;28411.43177136487;28617.713822861504;28747.42505149927;29262.82948682029;49896.50103498918;50421.74789126031;50450.99098017946;50584.735381615756;50667.49332506628;311999;314550;315726;315816;317055;29936.55095173618;29936.55095173618;30221.848890755406;30251.09246722602;30278.394432111676;42054.57945420629;43333.91665208395;43799.34300985424;44023.77988110039;44119.33820992752;29400.411991760473;35440.64559354373;35892.461613075306;36956;37185;35812.28375432529;35913;36469.817650912635;36699.816500917324;36714.89855304345;35674.28651427009;35883.82058089693;36415.63584364123;36481.452778207815;36662.450063248536;30503.694963050086;32669.509957351132;32708;32726.50910236301;32780.508292375154;24234.580424647305;24514;24580.139695110585;25094.37264068413;25138 15:21:29 INFO - PROCESS | 1676 | |2;jquery.html;304.27582353997485;367.1665319724226;370.05266517714654;370.41104643616643;374.859427714607;14052.789208161861;15830.762538561901;16383.508494745147;18769.812301876977;20575.38273851785;19804.900975495104;20008.899955500237;20445;20968.475788105305;20970.056347464364;6482.935170648292;6712.328767123273;6715.294894036129;6720.025596288535;6725.024871393645;4664;4880.585150262232;6636.701348439307;7273;7600.353969912537;7283.817904552402;7367.484276100675;7491.587962662052;7578.810529736773;7579.393648508106;6879.105716256905;7365.668544915492;7433.108027036763;7456.515326503787;7468.10382754073;6991.405730512913;7015.543989640708;7087.3267039631155;7101.396381307595;7104.68028938699;12474.625761227178;25372.746272537224;25984.87007564969;26085.869570652027;26297.3425664358;1985.4341512668964;1994.6210220058288;2131.605652954193;2187.6062308784412;2485.0432583455317;9227.630894764201;9642.614295428175;9760.414375137443;9888.95055524725;10113.595456181743;1766.7791526059243;1769.716845304745;1813.6554054729559;1845.4463660901677;1856.4709057918562;91325.54337228271;97764;99044.50477747565;99495;100188.49905750425;11130.387828669502;11202.60790872332;11203.21577489568;11229.3262404256;11296;13253.801192982113;13421.39603717835;13476.663083422993;13514.86485135136;13571.389287482087;8421.95789021051;8428.704995325133;8438.57807109642;8523.275521580612;8536.914630853613;6521.510886683518;6992.405645520086;8563.357748168912;8574.571271436527;8601.526916019553;3616.294822509585;4034.3141665916864;4207.97896010518;4208.6633069354375;4361.890952726144;1561.93752249911;1777.7777777777778;1841.4475657302755;1843.179784995664;1863.3105750872264;821.9643249505666;885.9114088591218;907.577976241051;913.456493386434;941.3692825806726;19411.3206037788;19950.90024549868;19951.800481995284;20015.799842001685;20165.39503814888;877.4647465046316;957;958.769895225141;1043.8068957242933;1055.7888422315568;10289.176865850714;10637.696537567235;10643.464386328002;10885.883271734223;10886.966384924126;768511;769483;773302;788413;788881;105284.47357763315;106185;108494;110435;110638.34042489209;1082.6860210538855;1126.5887950898027;1128.4019469680918;1140.8745038045809;1155.2086820527966;17544;17820.91089544544;17856.285748569913;17891;17968.730469043236;48917.26624100713;50004.749976249885;50541;52718.73640631772;54771;64535.35464645481;65414.34585654273;71148.64425677838;71191.28808711846;71267;7521.022267105242;7855;7856.489328193649;7879.763607091796;7881.369490440866;9457.479838608942;11181.440927953407;12211.084168687206;12364.938175309066;12592.244465331743;8636.524991125298;10458.163346932379;10654.57381704723;10872.456377180952;10955.851769692421;9212.355135140744;10446.42544660051;10603.257771955889;10745.623903163352;10829.29609575375;8353.20644538768;10735.087517561095;10840.45797710127;11084.224104312621;11172.441377930909;7515.473916825988;7556.526477337086;7765.301122898966;7780.260875216845;7881.251281128284;9284.442933424016;10992.340459572451;10999.780004399707;11115.555377784798;11218.38298893536 15:21:29 INFO - PROCESS | 1676 | |3;mootools.html;1362;1542.3290868472216;1557.1046648177294;1797.308036405984;1936.041659378607;7033.683484243208;7930.9206907930975;8694.39139260252;8699.565021748911;8746.562671866413;8359.874601880976;8628.741137765877;8656.697015604455;8667.826643467128;8669.869951950726;2123.617748805222;2202.4603972026875;2368.822338324624;2419.2258477287232;2443.58459061959;2425.0905910283645;2427.393151712072;2428.0287884846025;2432.4405386761055;2435.1111844176926;2163.729533808274;2345.2495201535517;2361.0791791201445;2423.7455067217866;2425.442148305891;1848.6579982703242;2130.7230060092243;2186.387811412807;2195.473086459246;2196.615592271346;2389.832711710181;2452.1294940296293;2458.299384675377;2460.138951367025;2462.273629279367;8519.233269005757;8602.698905538276;8603.268722158624;8612.224899759052;8614.87077693835;5272.156454967187;5368.973155134199;5375.086235340002;5398.190271459273;5405.459454054602;3561.71506279497;3569.4110471772387;3583.3191693578137;3665.9450108248398;3673;1044.0707770084555;1052.6631477927067;1060.9893901060968;1074.4735079810914;1637.6560922206352;11752.588659396966;11852.822207666892;11867.109966752529;11870.762584748256;11876.228045177122;4560.543945605413;4661.627069834405;4715.292706094045;4728.621710263171;4737.8104875805275;9263.953680231556;9267.25861931044;9268.443893366419;9276.814463710689;9283.535823208813;5330.280412144389;5332.040232758063;5343.3587969443915;5362.70505122222;5364.249005139283;4896.094222568836;5005.1491246488185;5007.799688012512;5015.724135172601;5016.347874776256;3569.37535931211;3619.3304238715923;3626.818659067036;3641.5266015417824;3697.6117507661306;1141.8572678415198;1144.6394385768456;1146.822242552389;1147.8852114788622;1151.83874257604;1082;1116.1238427834112;1116.5087361560886;1116.6929094498914;1283.8780315869978;8072.757817265491;8177.918220817715;8217.096119426858;8260.545669988089;8261.339092872557;492.79056401030135;501.58869726824383;503.97732102055494;505.33800721055536;506.5972551821311;5039.7228152451235;5125.615578831603;5981.940180598225;6344.746210151542;6452.645104519296;10380.844287335696;10464.319819211727;10652.84020739674;10693.411862347646;10697.572097116185;10486.265961382782;10578.047975682226;10585.894141058645;10596.417197054236;10602.151827853753;7964;8137.75586732399;8237.917620823715;8240.175982401592;8241.79395515117;8092.87860682102;8105.432619716799;8114.026316842017;8124.675012999413;8148.5518296494265;9403.764905877408;9503.809923801624;9759.707208783748;9788.265880059167;9806.362586431858;9850.901490985285;10094.545745441326;10111.090001899864;10111.241656875616;10269.897301027193;6973.616451095036;6991.405730512964;7080.610566418896;7092.113485814273;7103.467239956921;7731.342835859015;8022.197780221931;8039.598020098854;8196;8262.917370826215;7368.705251789868;7514.774556763306;7671.53970761756;7776.222377762178;7831.216878312123;7458.03045604068;7472.589007604634;7547.207543207884;7610.581418022062;7629.427792915664;7606.277403646644;7616.619169041636;7632.80917977055;7642.541447513167;7644.579548124907;6895.758648447481;6950.443964482728;6955.374016338554;6963.512554121163;6965.582065076112;6511.544191906521;7000.089988301488;7039.577625342496;7113.679884405317;7172.49792514519 15:21:29 INFO - PROCESS | 1676 | |4;prototype.html;262.9631851540785;324.65586478332955;328.28925376559755;329.1688486571408;334.79744754423587;6551.639659818708;6582.605043697382;6606.669666516679;6610.537262391635;6637.435817955467;6199.225096862892;6234.470070044052;6390.712417941192;6424.775132870351;6496.025596160578;4676.485586585472;4738.076075165344;4745.430548334205;4751.809927602892;4753.881152971168;4738.9526104739025;4744.098621261966;4755.381800365947;4758.547937945889;4775.044991001796;4512.12013657337;4634.351190833286;4671.509491503394;4672.15901137795;4673.813047478096;4597.793099310539;4736.928946065795;4779.784909679072;4783.306420568998;4788.61691064714;4068.186362727437;4083.2650122977852;4094.5086589609436;4100.9952561622595;4106.07613287008;6220.502359811204;6223.626582405071;6225.377462253784;6247.625142491465;6260.686965651699;5278.788848446057;5480.588955828329;5509.035918714249;5520.282363292787;5540.085885828834;6875.415589674883;6895.689693963784;6905.102336696247;6906.930930690679;6926.861462770767;4446.488653804821;4453.77731113443;4460.776961151962;4462.44219472566;4468.75421851798;7140.178879428829;7238.710451581983;7308.671109800019;7344.118705755237;7357.227491113463;4537.954620453819;4540.614047805908;4541.250693635513;4543.159515489645;4553.4518263789805;4619.9076018479445;4625.421822272216;4625.676202665848;4626.352310676508;4640.095181439654;5479.397266300703;5481.972590137024;5486.862828429321;5491.203775452537;5504.724763761876;5944.524438044946;6030.819075427744;6674.532782705164;6676.232233293234;6972.1284839395075;1580.217792192872;1607.0919930239377;1607.5900645335598;1608.4290077022722;1609.8390160984043;1015.746063484129;1024.114141267798;1036.3937096798456;1043.0612448795991;1043.5617040842712;6135.5398345124295;6522.988936714722;6525.575837570543;6526.575772574767;6527.5757075789925;7163.534370265916;7173.210946795848;7181.138263408425;7196.712131514785;7208.675609597581;6310.0850376695125;6444.806655800334;6503.642299673564;6605.339466053452;6635.535512514078;3783.621637836194;3805.7335986481235;3813.7139714521522;3830.3871380578976;3840.327942610032;6852.143482064742;6925;7343.302386273295;7582.810429739301;7583.938248645194;7414.555126692416;7418.629068546551;7424.146223184295;7425;7429.7399591014055;6398.456131228898;7290.270972902667;7522.924770752223;7563.546187228784;7578.507397019066;5818.650880947157;6988.720451181895;7240.8913866290995;7246.96376518114;7264.34620884123;7046.718131274692;7107.431405487652;7108.822279443055;7117.252688467636;7122.857542849218;7545.849083018199;7557.924420755722;7560.924390756022;7570.772876813704;7575.545467271982;4414;4530.501644819002;4549.067441174618;4554.43069616298;4559.931601025921;6120.867639486709;7173.0316407284345;7176.102987126609;7179.174394944648;7188.101487314086;6050.697465126638;6943.062686537252;6951.582905025714;6973.058637084132;6985.545939513915;6067.666278354556;6758.2903795102775;6949.339812717783;6967.860642787217;6977.162740471176;6791.185057793096;6799.898001529882;6802.387785099365;6805.081314022742;6816.829579260559;6766.323367663194;6927.203371612128;6965.686544105426;6975.6163411012885;6980.511364204457;6759.898601520883;6819.761308354184;6826.112605361272;6826.11260536147;6837.179538455417 15:21:29 INFO - PROCESS | 1676 | |5;yui.html;580.9709514524274;641.611824845968;661.8510835062109;703.6305939381821;732.3811379384418;15061.62345941352;15286.2356882156;15292.541223763277;15295.311710973005;15307.311170997304;13654.795178072336;13822.377992990314;13897;13993.720125597481;14028;872.1191596487546;887.40100432208;889.4263200235844;889.4929889962724;892.0054139634293;882.232457761745;883.4169448164213;886.8935727712684;887.6405055952317;892.6027917576681;932.9346945713803;933.1741408853146;937.2455173585261;938.3009657804911;938.723076692374;923.7690577355661;927.4481683398367;928.7956649537091;931.4644079654192;934.3599634250526;688.215434404779;688.7382794538044;690.5545922879714;690.6132565763189;691.350130876979;13318.334083295893;13341.53304634343;13359.666008349772;13366.665833354244;13371.53199638008;10876.945615271952;11054.778904421866;11077;11276.718082047932;11543.653690389301;9427.434353938786;9513.714588562354;9598.424094554279;9606.855897161548;9946.751331216705;2936.3833594945086;3436.7937923724407;3451;3497.108237399447;3501.6323286054853;80728;81208.59395703043;87996;89220.55389723074;90298.5485072577;9496.90503094974;9568.760780980392;9595.28035397348;9607.615695372248;10151.94924025375;20300.796992030188;20880.895595521924;20927.47681307949;20940;21016.684749728767;7628.313451789366;7703.191164927602;7752.7674169775;7763.573003484749;7779.57212353326;4226.154769046203;4228.619424251832;4249;4277.294246449301;4299.849505267301;3494.7029502492574;3533;3548.077499850006;3558.839852206657;3562.07386079616;648.6951132967498;655.2638872391819;658.4699317049725;661.0414898397343;670.3363669966698;558.8407303918364;619.1301221783401;632.5319263744888;634.1280738983897;636.3636363636364;8486.108958559385;8574.099719529484;8681.956590217009;8745.048209881132;8876.423032502867;549.6647045302362;562.8629572641056;595.0479233226889;595.7259660556193;609.9024156134997;3298.967010329866;3946.6842652587725;4031.5162180538523;4059.8579049733116;4082;96577.51711241399;100924;101069;101228;101266;66546;73756;78025;85157;85663.57168214119;69665;70884.93672594916;71023.2897671027;72179.63910180416;76011.61994189993;8884.822303553763;8948.955255223682;9053.728388148365;9122.452652840851;9181.77045573866;50906.49093509166;51172.74413627908;51750.96498069942;51865;53794.65513362197;57911;68623;68840;71878.28121718924;72336;9264.490453025148;10789.298695584763;10833.945830270799;10895.509702063267;11047.889521104686;11937.28376297425;11945.701357466132;11948.044156467635;11953.163278570417;11979.28124312544;11348.432578371214;11354.886451135382;11370;11377.431128443379;11385.772284554429;9497.002814704356;10460.556723472117;10885.782284354427;10991.06575941054;11030.558777648805;10666.36001839892;11246.66260012201;11486.770264594828;11523.769524609628;11782.410879456163;9399.154076133182;9630.00709411173;9656.082672146134;9680.903190968;10029;9747.463889486145;9887.258455615714;9971.551280192263;10008.699739007841;10065.295429319878 15:21:29 INFO - PROCESS | 1676 | __end_tp_report 15:21:29 INFO - PROCESS | 1676 | __start_cc_report 15:21:29 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1573 15:21:29 INFO - PROCESS | 1676 | __end_cc_report 15:21:29 INFO - PROCESS | 1676 | __startTimestamp1455751289241__endTimestamp 15:21:29 INFO - PROCESS | 1676 | 15:21:29 INFO - PROCESS | 1676 | ------- Summary: start ------- 15:21:29 INFO - PROCESS | 1676 | Number of tests: 6 15:21:29 INFO - PROCESS | 1676 | 15:21:29 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5681.89 Median:5870.38 stddev:649.16 (11.1%) stddev-sans-first:623.92 15:21:29 INFO - PROCESS | 1676 | Values: 3309.9 3365.3 3443.3 3452.2 3486.8 5560.9 5580.7 5609.3 5621.8 5633.7 5616.9 5629.5 5649.7 5750.3 5763.4 5036.3 5081.2 5098.4 5105.5 5194.7 5176.5 5179.8 5200.8 5225.7 5256.5 5053.6 5060.5 5142.8 5152.9 5185.5 5127.7 5151.3 5151.9 5153.9 5178.5 5063.3 5087.3 5103.3 5119.1 5134.7 4668.8 5413.9 5568.1 5571.1 5670.8 6197.8 6208.0 6211.0 6215.8 6228.3 6214.5 6241.4 6252.7 6259.2 6261.2 5629.5 5679.5 5716.3 5718.1 5719.5 6302.8 6324.5 6324.8 6334.8 6334.9 5999.3 6010.4 6066.3 6089.1 6099.8 6060.0 6107.6 6110.2 6155.4 6224.5 6120.1 6149.5 6171.7 6175.7 6187.3 6165.3 6185.1 6191.1 6195.9 6199.0 4795.5 4840.8 4861.7 4866.9 4897.5 4049.7 4189.1 4337.3 4435.6 4444.8 5620.9 5694.8 5703.7 5704.0 5722.5 6185.3 6188.9 6220.7 6223.9 6225.5 4753.6 5594.2 5694.5 5701.3 5710.7 5329.5 5338.0 5376.9 5379.5 5394.0 6265.2 6274.3 6283.0 6300.0 6302.2 6120.8 6231.2 6240.9 6242.3 6251.4 6254.2 6287.2 6305.4 6314.3 6316.4 6090.4 6116.0 6117.4 6122.6 6132.9 5880.9 6159.0 6209.3 6213.3 6221.3 6021.8 6068.6 6086.2 6106.9 6125.5 6020.7 6067.6 6075.4 6078.5 6089.1 5737.1 5851.9 5854.1 5908.5 5910.9 5868.3 5921.0 5956.0 5963.6 5986.3 5791.1 5852.2 5868.1 5870.4 5873.7 15:21:29 INFO - PROCESS | 1676 | 15:21:29 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:59449.96 Median:37025.04 stddev:120707.59 (326.0%) stddev-sans-first:120964.95 15:21:29 INFO - PROCESS | 1676 | Values: 458.7 476.0 495.1 526.2 579.5 26106.0 30273.7 30399.2 36829.3 37094.1 33670.5 40931.2 41295.0 41329.0 41369.0 9397.8 9629.0 9630.6 9634.8 9659.6 9439.4 9582.6 9583.5 9590.4 9591.2 8352.5 9787.8 9797.9 9804.2 9811.9 9677.8 9747.6 9759.3 9776.9 9777.6 9614.3 9761.5 9763.9 9764.7 9770.2 40282.2 40637.8 40638.6 40646.6 40721.7 3063.5 3201.7 3491.7 3741.7 3853.9 28888.6 29016.0 29821.0 29830.9 29886.0 2736.2 2762.2 2833.3 2849.8 2850.5 477964.0 478583.0 478954.6 479706.0 480463.0 64086.4 64091.7 64492.4 64583.4 64659.7 67959.3 67988.0 68128.3 68136.0 68345.0 16419.9 16539.0 16546.3 16566.3 16594.0 12559.4 12620.8 12641.6 12644.6 12649.9 16166.4 19778.3 19869.1 19894.5 19900.9 8470.8 8585.4 8684.0 8733.4 8803.2 805.3 921.3 977.5 988.6 1031.2 26226.3 26402.9 30007.0 30546.4 30705.8 865.6 950.3 1042.4 1054.2 1165.9 15547.5 15576.9 15619.3 15694.8 15841.0 526995.0 537696.3 538060.0 540292.0 543051.0 57577.7 57979.4 58107.3 58249.4 58270.0 1214.1 1266.9 1407.4 1419.9 1426.7 26193.8 28411.4 28617.7 28747.4 29262.8 49896.5 50421.7 50451.0 50584.7 50667.5 311999.0 314550.0 315726.0 315816.0 317055.0 29936.6 29936.6 30221.8 30251.1 30278.4 42054.6 43333.9 43799.3 44023.8 44119.3 29400.4 35440.6 35892.5 36956.0 37185.0 35812.3 35913.0 36469.8 36699.8 36714.9 35674.3 35883.8 36415.6 36481.5 36662.5 30503.7 32669.5 32708.0 32726.5 32780.5 24234.6 24514.0 24580.1 25094.4 25138.0 15:21:29 INFO - PROCESS | 1676 | 15:21:29 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:38341.64 Median:1991.73 stddev:127748.24 (6413.9%) stddev-sans-first:128074.67 15:21:29 INFO - PROCESS | 1676 | Values: 304.3 367.2 370.1 370.4 374.9 14052.8 15830.8 16383.5 18769.8 20575.4 19804.9 20008.9 20445.0 20968.5 20970.1 6482.9 6712.3 6715.3 6720.0 6725.0 4664.0 4880.6 6636.7 7273.0 7600.4 7283.8 7367.5 7491.6 7578.8 7579.4 6879.1 7365.7 7433.1 7456.5 7468.1 6991.4 7015.5 7087.3 7101.4 7104.7 12474.6 25372.7 25984.9 26085.9 26297.3 1985.4 1994.6 2131.6 2187.6 2485.0 9227.6 9642.6 9760.4 9889.0 10113.6 1766.8 1769.7 1813.7 1845.4 1856.5 91325.5 97764.0 99044.5 99495.0 100188.5 11130.4 11202.6 11203.2 11229.3 11296.0 13253.8 13421.4 13476.7 13514.9 13571.4 8422.0 8428.7 8438.6 8523.3 8536.9 6521.5 6992.4 8563.4 8574.6 8601.5 3616.3 4034.3 4208.0 4208.7 4361.9 1561.9 1777.8 1841.4 1843.2 1863.3 822.0 885.9 907.6 913.5 941.4 19411.3 19950.9 19951.8 20015.8 20165.4 877.5 957.0 958.8 1043.8 1055.8 10289.2 10637.7 10643.5 10885.9 10887.0 768511.0 769483.0 773302.0 788413.0 788881.0 105284.5 106185.0 108494.0 110435.0 110638.3 1082.7 1126.6 1128.4 1140.9 1155.2 17544.0 17820.9 17856.3 17891.0 17968.7 48917.3 50004.7 50541.0 52718.7 54771.0 64535.4 65414.3 71148.6 71191.3 71267.0 7521.0 7855.0 7856.5 7879.8 7881.4 9457.5 11181.4 12211.1 12364.9 12592.2 8636.5 10458.2 10654.6 10872.5 10955.9 9212.4 10446.4 10603.3 10745.6 10829.3 8353.2 10735.1 10840.5 11084.2 11172.4 7515.5 7556.5 7765.3 7780.3 7881.3 9284.4 10992.3 10999.8 11115.6 11218.4 15:21:29 INFO - PROCESS | 1676 | 15:21:29 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5954.54 Median:5198.89 stddev:3202.64 (61.6%) stddev-sans-first:3193.02 15:21:29 INFO - PROCESS | 1676 | Values: 1362.0 1542.3 1557.1 1797.3 1936.0 7033.7 7930.9 8694.4 8699.6 8746.6 8359.9 8628.7 8656.7 8667.8 8669.9 2123.6 2202.5 2368.8 2419.2 2443.6 2425.1 2427.4 2428.0 2432.4 2435.1 2163.7 2345.2 2361.1 2423.7 2425.4 1848.7 2130.7 2186.4 2195.5 2196.6 2389.8 2452.1 2458.3 2460.1 2462.3 8519.2 8602.7 8603.3 8612.2 8614.9 5272.2 5369.0 5375.1 5398.2 5405.5 3561.7 3569.4 3583.3 3665.9 3673.0 1044.1 1052.7 1061.0 1074.5 1637.7 11752.6 11852.8 11867.1 11870.8 11876.2 4560.5 4661.6 4715.3 4728.6 4737.8 9264.0 9267.3 9268.4 9276.8 9283.5 5330.3 5332.0 5343.4 5362.7 5364.2 4896.1 5005.1 5007.8 5015.7 5016.3 3569.4 3619.3 3626.8 3641.5 3697.6 1141.9 1144.6 1146.8 1147.9 1151.8 1082.0 1116.1 1116.5 1116.7 1283.9 8072.8 8177.9 8217.1 8260.5 8261.3 492.8 501.6 504.0 505.3 506.6 5039.7 5125.6 5981.9 6344.7 6452.6 10380.8 10464.3 10652.8 10693.4 10697.6 10486.3 10578.0 10585.9 10596.4 10602.2 7964.0 8137.8 8237.9 8240.2 8241.8 8092.9 8105.4 8114.0 8124.7 8148.6 9403.8 9503.8 9759.7 9788.3 9806.4 9850.9 10094.5 10111.1 10111.2 10269.9 6973.6 6991.4 7080.6 7092.1 7103.5 7731.3 8022.2 8039.6 8196.0 8262.9 7368.7 7514.8 7671.5 7776.2 7831.2 7458.0 7472.6 7547.2 7610.6 7629.4 7606.3 7616.6 7632.8 7642.5 7644.6 6895.8 6950.4 6955.4 6963.5 6965.6 6511.5 7000.1 7039.6 7113.7 7172.5 15:21:29 INFO - PROCESS | 1676 | 15:21:29 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:5648.09 Median:6394.58 stddev:1802.54 (28.2%) stddev-sans-first:1761.70 15:21:29 INFO - PROCESS | 1676 | Values: 263.0 324.7 328.3 329.2 334.8 6551.6 6582.6 6606.7 6610.5 6637.4 6199.2 6234.5 6390.7 6424.8 6496.0 4676.5 4738.1 4745.4 4751.8 4753.9 4739.0 4744.1 4755.4 4758.5 4775.0 4512.1 4634.4 4671.5 4672.2 4673.8 4597.8 4736.9 4779.8 4783.3 4788.6 4068.2 4083.3 4094.5 4101.0 4106.1 6220.5 6223.6 6225.4 6247.6 6260.7 5278.8 5480.6 5509.0 5520.3 5540.1 6875.4 6895.7 6905.1 6906.9 6926.9 4446.5 4453.8 4460.8 4462.4 4468.8 7140.2 7238.7 7308.7 7344.1 7357.2 4538.0 4540.6 4541.3 4543.2 4553.5 4619.9 4625.4 4625.7 4626.4 4640.1 5479.4 5482.0 5486.9 5491.2 5504.7 5944.5 6030.8 6674.5 6676.2 6972.1 1580.2 1607.1 1607.6 1608.4 1609.8 1015.7 1024.1 1036.4 1043.1 1043.6 6135.5 6523.0 6525.6 6526.6 6527.6 7163.5 7173.2 7181.1 7196.7 7208.7 6310.1 6444.8 6503.6 6605.3 6635.5 3783.6 3805.7 3813.7 3830.4 3840.3 6852.1 6925.0 7343.3 7582.8 7583.9 7414.6 7418.6 7424.1 7425.0 7429.7 6398.5 7290.3 7522.9 7563.5 7578.5 5818.7 6988.7 7240.9 7247.0 7264.3 7046.7 7107.4 7108.8 7117.3 7122.9 7545.8 7557.9 7560.9 7570.8 7575.5 4414.0 4530.5 4549.1 4554.4 4559.9 6120.9 7173.0 7176.1 7179.2 7188.1 6050.7 6943.1 6951.6 6973.1 6985.5 6067.7 6758.3 6949.3 6967.9 6977.2 6791.2 6799.9 6802.4 6805.1 6816.8 6766.3 6927.2 6965.7 6975.6 6980.5 6759.9 6819.8 6826.1 6826.1 6837.2 15:21:29 INFO - PROCESS | 1676 | 15:21:29 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:18672.44 Median:633.33 stddev:26927.55 (4251.7%) stddev-sans-first:26968.83 15:21:29 INFO - PROCESS | 1676 | Values: 581.0 641.6 661.9 703.6 732.4 15061.6 15286.2 15292.5 15295.3 15307.3 13654.8 13822.4 13897.0 13993.7 14028.0 872.1 887.4 889.4 889.5 892.0 882.2 883.4 886.9 887.6 892.6 932.9 933.2 937.2 938.3 938.7 923.8 927.4 928.8 931.5 934.4 688.2 688.7 690.6 690.6 691.4 13318.3 13341.5 13359.7 13366.7 13371.5 10876.9 11054.8 11077.0 11276.7 11543.7 9427.4 9513.7 9598.4 9606.9 9946.8 2936.4 3436.8 3451.0 3497.1 3501.6 80728.0 81208.6 87996.0 89220.6 90298.5 9496.9 9568.8 9595.3 9607.6 10151.9 20300.8 20880.9 20927.5 20940.0 21016.7 7628.3 7703.2 7752.8 7763.6 7779.6 4226.2 4228.6 4249.0 4277.3 4299.8 3494.7 3533.0 3548.1 3558.8 3562.1 648.7 655.3 658.5 661.0 670.3 558.8 619.1 632.5 634.1 636.4 8486.1 8574.1 8682.0 8745.0 8876.4 549.7 562.9 595.0 595.7 609.9 3299.0 3946.7 4031.5 4059.9 4082.0 96577.5 100924.0 101069.0 101228.0 101266.0 66546.0 73756.0 78025.0 85157.0 85663.6 69665.0 70884.9 71023.3 72179.6 76011.6 8884.8 8949.0 9053.7 9122.5 9181.8 50906.5 51172.7 51751.0 51865.0 53794.7 57911.0 68623.0 68840.0 71878.3 72336.0 9264.5 10789.3 10833.9 10895.5 11047.9 11937.3 11945.7 11948.0 11953.2 11979.3 11348.4 11354.9 11370.0 11377.4 11385.8 9497.0 10460.6 10885.8 10991.1 11030.6 10666.4 11246.7 11486.8 11523.8 11782.4 9399.2 9630.0 9656.1 9680.9 10029.0 9747.5 9887.3 9971.6 10008.7 10065.3 15:21:29 INFO - PROCESS | 1676 | -------- Summary: end -------- 15:21:29 INFO - PROCESS | 1676 | 15:21:29 INFO - TEST-INFO | 1676: exit 0 15:21:29 INFO - TEST-OK | dromaeo_css | took 1113745ms 15:21:29 INFO - TEST-START | kraken 15:21:29 INFO - operating with platform_type : mac_ 15:21:29 INFO - Initialising browser for kraken test... 15:21:29 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpyKvmbC/profile http://localhost:49279/getInfo.html) 15:21:31 INFO - PROCESS | 1685 | __metrics Screen width/height:1600/1200 15:21:31 INFO - PROCESS | 1685 | colorDepth:24 15:21:31 INFO - PROCESS | 1685 | Browser inner width/height: 1024/689 15:21:31 INFO - PROCESS | 1685 | __metrics 15:21:32 INFO - PROCESS | 1685 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:21:32 INFO - PROCESS | 1685 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:21:32 INFO - TEST-INFO | 1685: exit 0 15:21:32 INFO - Browser initialized. 15:21:32 INFO - Running cycle 1/1 for kraken test... 15:21:32 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpyKvmbC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:44 INFO - PROCESS | 1687 | RSS: Main: 244666368 15:21:44 INFO - PROCESS | 1687 | 15:21:47 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 15:21:47 INFO - PROCESS | 1687 | RSS: Main: 290549760 15:21:47 INFO - PROCESS | 1687 | 15:21:50 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 15:21:50 INFO - PROCESS | 1687 | RSS: Main: 301322240 15:21:50 INFO - PROCESS | 1687 | 15:21:54 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 15:21:54 INFO - PROCESS | 1687 | RSS: Main: 286195712 15:21:54 INFO - PROCESS | 1687 | 15:21:56 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 15:21:57 INFO - PROCESS | 1687 | RSS: Main: 287563776 15:21:57 INFO - PROCESS | 1687 | 15:21:59 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:21:59 INFO - PROCESS | 1687 | RSS: Main: 289042432 15:21:59 INFO - PROCESS | 1687 | 15:22:02 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 15:22:03 INFO - PROCESS | 1687 | RSS: Main: 289202176 15:22:03 INFO - PROCESS | 1687 | 15:22:06 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 15:22:06 INFO - PROCESS | 1687 | RSS: Main: 289472512 15:22:06 INFO - PROCESS | 1687 | 15:22:09 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 15:22:10 INFO - PROCESS | 1687 | RSS: Main: 290545664 15:22:10 INFO - PROCESS | 1687 | 15:22:11 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:22:12 INFO - PROCESS | 1687 | RSS: Main: 323756032 15:22:12 INFO - PROCESS | 1687 | 15:22:14 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:22:14 INFO - PROCESS | 1687 | RSS: Main: 322560000 15:22:14 INFO - PROCESS | 1687 | 15:22:16 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:22:17 INFO - PROCESS | 1687 | RSS: Main: 333176832 15:22:17 INFO - PROCESS | 1687 | 15:22:19 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:22:20 INFO - PROCESS | 1687 | RSS: Main: 333037568 15:22:20 INFO - PROCESS | 1687 | 15:22:23 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:22:23 INFO - PROCESS | 1687 | RSS: Main: 332673024 15:22:23 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 15:22:25 INFO - PROCESS | 1687 | RSS: Main: 394821632 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | __start_tp_report 15:22:25 INFO - PROCESS | 1687 | _x_x_mozilla_page_load 15:22:25 INFO - PROCESS | 1687 | _x_x_mozilla_page_load_details 15:22:25 INFO - PROCESS | 1687 | |i|pagename|runs| 15:22:25 INFO - PROCESS | 1687 | |0;ai-astar;112;107;110;111;109;111;115;110;110;112 15:22:25 INFO - PROCESS | 1687 | |1;audio-beat-detection;157;121;144;157;122;142;141;120;168;148 15:22:25 INFO - PROCESS | 1687 | |2;audio-dft;184;183;185;183;184;185;185;185;180;180 15:22:25 INFO - PROCESS | 1687 | |3;audio-fft;72;81;86;77;57;58;71;79;76;56 15:22:25 INFO - PROCESS | 1687 | |4;audio-oscillator;85;98;128;78;81;84;108;77;79;110 15:22:25 INFO - PROCESS | 1687 | |5;imaging-gaussian-blur;84;90;99;140;83;90;84;91;84;89 15:22:25 INFO - PROCESS | 1687 | |6;imaging-darkroom;108;147;93;93;108;149;93;93;93;92 15:22:25 INFO - PROCESS | 1687 | |7;imaging-desaturate;85;87;86;87;85;88;88;85;88;87 15:22:25 INFO - PROCESS | 1687 | |8;json-parse-financial;57;54;55;55;55;54;55;64;52;53 15:22:25 INFO - PROCESS | 1687 | |9;json-stringify-tinderbox;64;65;72;75;70;67;66;67;68;68 15:22:25 INFO - PROCESS | 1687 | |10;stanford-crypto-aes;84;83;83;96;102;81;83;83;85;84 15:22:25 INFO - PROCESS | 1687 | |11;stanford-crypto-ccm;122;127;127;124;121;121;123;125;127;122 15:22:25 INFO - PROCESS | 1687 | |12;stanford-crypto-pbkdf2;138;128;167;168;140;137;151;139;136;171 15:22:25 INFO - PROCESS | 1687 | |13;stanford-crypto-sha256-iterative;67;67;65;65;67;68;67;66;67;66 15:22:25 INFO - PROCESS | 1687 | __end_tp_report 15:22:25 INFO - PROCESS | 1687 | __start_cc_report 15:22:25 INFO - PROCESS | 1687 | _x_x_mozilla_cycle_collect,1162 15:22:25 INFO - PROCESS | 1687 | __end_cc_report 15:22:25 INFO - PROCESS | 1687 | __startTimestamp1455751345631__endTimestamp 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | ------- Summary: start ------- 15:22:25 INFO - PROCESS | 1687 | Number of tests: 14 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:2.11 (1.9%) stddev-sans-first:2.19 15:22:25 INFO - PROCESS | 1687 | Values: 112.0 107.0 110.0 111.0 109.0 111.0 115.0 110.0 110.0 112.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:146.00 stddev:16.64 (11.4%) stddev-sans-first:16.74 15:22:25 INFO - PROCESS | 1687 | Values: 157.0 121.0 144.0 157.0 122.0 142.0 141.0 120.0 168.0 148.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.50 stddev:1.96 (1.1%) stddev-sans-first:2.06 15:22:25 INFO - PROCESS | 1687 | Values: 184.0 183.0 185.0 183.0 184.0 185.0 185.0 185.0 180.0 180.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#3] audio-fft Cycles:10 Average:71.30 Median:76.50 stddev:10.75 (14.1%) stddev-sans-first:11.40 15:22:25 INFO - PROCESS | 1687 | Values: 72.0 81.0 86.0 77.0 57.0 58.0 71.0 79.0 76.0 56.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#4] audio-oscillator Cycles:10 Average:92.80 Median:80.00 stddev:17.42 (21.8%) stddev-sans-first:18.24 15:22:25 INFO - PROCESS | 1687 | Values: 85.0 98.0 128.0 78.0 81.0 84.0 108.0 77.0 79.0 110.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#5] imaging-gaussian-blur Cycles:10 Average:93.40 Median:89.50 stddev:17.08 (19.1%) stddev-sans-first:17.77 15:22:25 INFO - PROCESS | 1687 | Values: 84.0 90.0 99.0 140.0 83.0 90.0 84.0 91.0 84.0 89.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#6] imaging-darkroom Cycles:10 Average:106.90 Median:93.00 stddev:22.54 (24.2%) stddev-sans-first:23.90 15:22:25 INFO - PROCESS | 1687 | Values: 108.0 147.0 93.0 93.0 108.0 149.0 93.0 93.0 93.0 92.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#7] imaging-desaturate Cycles:10 Average:86.60 Median:87.00 stddev:1.26 (1.5%) stddev-sans-first:1.20 15:22:25 INFO - PROCESS | 1687 | Values: 85.0 87.0 86.0 87.0 85.0 88.0 88.0 85.0 88.0 87.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:3.31 (6.0%) stddev-sans-first:3.46 15:22:25 INFO - PROCESS | 1687 | Values: 57.0 54.0 55.0 55.0 55.0 54.0 55.0 64.0 52.0 53.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:3.33 (4.9%) stddev-sans-first:3.16 15:22:25 INFO - PROCESS | 1687 | Values: 64.0 65.0 72.0 75.0 70.0 67.0 66.0 67.0 68.0 68.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:83.50 stddev:6.87 (8.2%) stddev-sans-first:7.23 15:22:25 INFO - PROCESS | 1687 | Values: 84.0 83.0 83.0 96.0 102.0 81.0 83.0 83.0 85.0 84.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:124.50 stddev:2.47 (2.0%) stddev-sans-first:2.52 15:22:25 INFO - PROCESS | 1687 | Values: 122.0 127.0 127.0 124.0 121.0 121.0 123.0 125.0 127.0 122.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.50 Median:145.50 stddev:15.66 (10.8%) stddev-sans-first:16.23 15:22:25 INFO - PROCESS | 1687 | Values: 138.0 128.0 167.0 168.0 140.0 137.0 151.0 139.0 136.0 171.0 15:22:25 INFO - PROCESS | 1687 | 15:22:25 INFO - PROCESS | 1687 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 15:22:25 INFO - PROCESS | 1687 | Values: 67.0 67.0 65.0 65.0 67.0 68.0 67.0 66.0 67.0 66.0 15:22:25 INFO - PROCESS | 1687 | -------- Summary: end -------- 15:22:25 INFO - PROCESS | 1687 | 15:22:26 INFO - TEST-INFO | 1687: exit 0 15:22:26 INFO - TEST-OK | kraken | took 56589ms 15:22:26 INFO - SUITE-END | took 1170s 15:22:26 INFO - Completed test suite (00:19:30) 15:22:26 INFO - Working with test: dromaeo_css 15:22:26 INFO - Generating results file: dromaeo_css 15:22:26 INFO - Working with test: kraken 15:22:26 INFO - Generating results file: kraken 15:22:26 INFO - javascript benchmark 15:22:26 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:22:27 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 15:22:27 INFO - process_Request line: dromaeo_css 7431.91 graph.html#tests=[[72,64,59]] 15:22:27 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:22:27 INFO - process_Request line: kraken 1435.00 graph.html#tests=[[232,64,59]] 15:22:27 INFO - RETURN: dromaeo_css: 7431.91 15:22:27 INFO - RETURN: kraken: 1435.00 15:22:27 INFO - javascript benchmark 15:22:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3309.9338013239735, 3365.276465559905, 3443.3285509325674, 3452.205992621697, 3486.755927085102, 5560.916586251209, 5580.748866301016, 5609.3268807743025, 5621.77512899484, 5633.718314084294, 5616.943830561693, 5629.465200805927, 5649.689267090308, 5750.33871104822, 5763.366029736726, 5036.294918711365, 5081.237814327844, 5098.4136824265315, 5105.463926287742, 5194.71429071401, 5176.482351764831, 5179.766910489018, 5200.843974680765, 5225.686458812484, 5256.526912577867, 5053.620978426614, 5060.544550990428, 5142.820001299937, 5152.871178220537, 5185.455527169631, 5127.66670166438, 5151.304573882516, 5151.922721159186, 5153.948460515422, 5178.508041736029, 5063.341765570489, 5087.313212716273, 5103.260027296022, 5119.10415677255, 5134.691918484903, 4668.836590719308, 5413.890152518724, 5568.081266591008, 5571.0807716726695, 5670.829875103754, 6197.845053873644, 6207.96896015517, 6211.0, 6215.751369945242, 6228.314885362606, 6214.502839772853, 6241.375862413768, 6252.6561039142825, 6259.155014073087, 6261.186045814015, 5629.549636029149, 5679.5172410345585, 5716.314042314907, 5718.142278658193, 5719.542436605062, 6302.810915672537, 6324.462420694293, 6324.810255692337, 6334.778282760081, 6334.873302533923, 5999.250093738283, 6010.3689112643415, 6066.332703402622, 6089.056196289582, 6099.817005489843, 6059.969700151559, 6107.552954153613, 6110.194261626863, 6155.384461553898, 6224.470919971853, 6120.142398366865, 6149.538784591174, 6171.691415429211, 6175.660338681326, 6187.257529096447, 6165.32181460039, 6185.103160041769, 6191.102290167991, 6195.938040619626, 6199.0, 4795.544423279783, 4840.782164802592, 4861.732604706775, 4866.853994380174, 4897.510248975145, 4049.7445791804635, 4189.141226048653, 4337.327714204303, 4435.556444355603, 4444.844430444984, 5620.859478512988, 5694.829155125353, 5703.686297253691, 5704.0, 5722.54219662426, 6185.288691800501, 6188.876222475616, 6220.720067596879, 6223.906641400383, 6225.533085018551, 4753.572178503882, 5594.1888426177975, 5694.4590263924865, 5701.315842098892, 5710.71446427677, 5329.520343169133, 5337.985782701275, 5376.946230537723, 5379.515843574019, 5393.9730301348245, 6265.185525881606, 6274.2784579773, 6283.0, 6300.0, 6302.243730752431, 6120.7551697931585, 6231.22109736283, 6240.939040363059, 6242.250929888442, 6251.4061164189325, 6254.186955695731, 6287.245530536365, 6305.369463053841, 6314.273858506331, 6316.368363163647, 6090.3605121461605, 6115.969420152871, 6117.357677443804, 6122.571420000557, 6132.877342453036, 5880.94119058804, 6158.969205153946, 6209.285932117684, 6213.254409470893, 6221.3156552778255, 6021.819345419644, 6068.575199736153, 6086.2087928569, 6106.885399019288, 6125.509959203342, 6020.729067191899, 6067.575269731076, 6075.392460753889, 6078.54410919174, 6089.086637004485, 5737.110747834093, 5851.912221316598, 5854.092615644582, 5908.497777688773, 5910.881782364415, 5868.295804503487, 5920.970395147997, 5955.970220148872, 5963.582549221686, 5986.281646202309, 5791.102379131242, 5852.151438041375, 5868.119782032559, 5870.383609721089, 5873.706314684334], "unit": "score", "name": "dojo.html", "value": 5639.905304339739}, {"lowerIsBetter": false, "replicates": [458.68580022684455, 475.9505290833712, 495.093978020223, 526.2185654303358, 579.5016285994042, 26106.0, 30273.697263027363, 30399.240018999535, 36829.263414731686, 37094.07264818381, 33670.49494257582, 40931.18137637246, 41295.0, 41329.0, 41369.0, 9397.765055873606, 9629.037096290385, 9630.566624501915, 9634.807303853919, 9659.613615455408, 9439.433633981984, 9582.568784404719, 9583.472908990003, 9590.376625519353, 9591.184749296319, 8352.498850068985, 9787.804243915152, 9797.853032204523, 9804.166645835112, 9811.852822207637, 9677.806443871119, 9747.610095596168, 9759.316847820655, 9776.90223097767, 9777.608895644165, 9614.326997110205, 9761.463119528424, 9763.853542196874, 9764.707058788248, 9770.218382529381, 40282.19435611301, 40637.79681101605, 40638.59361406378, 40646.577369791914, 40721.70617819694, 3063.5251536011733, 3201.679832016803, 3491.703205227533, 3741.681957033655, 3853.9401664542474, 28888.566671499946, 29016.0, 29821.0, 29830.85084574585, 29886.0, 2736.165469531792, 2762.185155379168, 2833.3058400691757, 2849.843258620775, 2850.5439129739143, 477964.0, 478583.0, 478954.6052269751, 479706.0, 480463.0, 64086.35913640897, 64091.67954160293, 64492.355076449574, 64583.354166458674, 64659.67670161619, 67959.3204067963, 67988.0, 68128.31871681318, 68136.0, 68345.0, 16419.917900410423, 16539.007659540464, 16546.33814647425, 16566.337346506247, 16594.0, 12559.372031398394, 12620.81068783969, 12641.620751377473, 12644.557440489538, 12649.936750316374, 16166.434174803826, 19778.318842898516, 19869.105890234972, 19894.502637433892, 19900.90049549772, 8470.788230294169, 8585.399022068521, 8684.0, 8733.38866279367, 8803.207711306013, 805.3476683886071, 921.2906062331967, 977.4819345746765, 988.5699720621552, 1031.1544533482622, 26226.344341391617, 26402.86798565995, 30007.0, 30546.38907221843, 30705.846470767505, 865.5801936061006, 950.3395140377371, 1042.4266653340633, 1054.1891063804444, 1165.9417029148678, 15547.533573992798, 15576.92211538935, 15619.297131629104, 15694.843051569338, 15841.0, 526995.0, 537696.3115184399, 538060.0, 540292.0, 543051.0, 57577.71211143918, 57979.42020579825, 58107.2567822966, 58249.417505825244, 58270.04704600072, 1214.107630891294, 1266.9429875655617, 1407.3877863129571, 1419.8722115009286, 1426.686129051607, 26193.780058986427, 28411.43177136487, 28617.713822861504, 28747.42505149927, 29262.82948682029, 49896.50103498918, 50421.74789126031, 50450.99098017946, 50584.735381615756, 50667.49332506628, 311999.0, 314550.0, 315726.0, 315816.0, 317055.0, 29936.55095173618, 29936.55095173618, 30221.848890755406, 30251.09246722602, 30278.394432111676, 42054.57945420629, 43333.91665208395, 43799.34300985424, 44023.77988110039, 44119.33820992752, 29400.411991760473, 35440.64559354373, 35892.461613075306, 36956.0, 37185.0, 35812.28375432529, 35913.0, 36469.817650912635, 36699.816500917324, 36714.89855304345, 35674.28651427009, 35883.82058089693, 36415.63584364123, 36481.452778207815, 36662.450063248536, 30503.694963050086, 32669.509957351132, 32708.0, 32726.50910236301, 32780.508292375154, 24234.580424647305, 24514.0, 24580.139695110585, 25094.37264068413, 25138.0], "unit": "score", "name": "ext.html", "value": 19264.01335791643}, {"lowerIsBetter": false, "replicates": [304.27582353997485, 367.1665319724226, 370.05266517714654, 370.41104643616643, 374.859427714607, 14052.789208161861, 15830.762538561901, 16383.508494745147, 18769.812301876977, 20575.38273851785, 19804.900975495104, 20008.899955500237, 20445.0, 20968.475788105305, 20970.056347464364, 6482.935170648292, 6712.328767123273, 6715.294894036129, 6720.025596288535, 6725.024871393645, 4664.0, 4880.585150262232, 6636.701348439307, 7273.0, 7600.353969912537, 7283.817904552402, 7367.484276100675, 7491.587962662052, 7578.810529736773, 7579.393648508106, 6879.105716256905, 7365.668544915492, 7433.108027036763, 7456.515326503787, 7468.10382754073, 6991.405730512913, 7015.543989640708, 7087.3267039631155, 7101.396381307595, 7104.68028938699, 12474.625761227178, 25372.746272537224, 25984.87007564969, 26085.869570652027, 26297.3425664358, 1985.4341512668964, 1994.6210220058288, 2131.605652954193, 2187.6062308784412, 2485.0432583455317, 9227.630894764201, 9642.614295428175, 9760.414375137443, 9888.95055524725, 10113.595456181743, 1766.7791526059243, 1769.716845304745, 1813.6554054729559, 1845.4463660901677, 1856.4709057918562, 91325.54337228271, 97764.0, 99044.50477747565, 99495.0, 100188.49905750425, 11130.387828669502, 11202.60790872332, 11203.21577489568, 11229.3262404256, 11296.0, 13253.801192982113, 13421.39603717835, 13476.663083422993, 13514.86485135136, 13571.389287482087, 8421.95789021051, 8428.704995325133, 8438.57807109642, 8523.275521580612, 8536.914630853613, 6521.510886683518, 6992.405645520086, 8563.357748168912, 8574.571271436527, 8601.526916019553, 3616.294822509585, 4034.3141665916864, 4207.97896010518, 4208.6633069354375, 4361.890952726144, 1561.93752249911, 1777.7777777777778, 1841.4475657302755, 1843.179784995664, 1863.3105750872264, 821.9643249505666, 885.9114088591218, 907.577976241051, 913.456493386434, 941.3692825806726, 19411.3206037788, 19950.90024549868, 19951.800481995284, 20015.799842001685, 20165.39503814888, 877.4647465046316, 957.0, 958.769895225141, 1043.8068957242933, 1055.7888422315568, 10289.176865850714, 10637.696537567235, 10643.464386328002, 10885.883271734223, 10886.966384924126, 768511.0, 769483.0, 773302.0, 788413.0, 788881.0, 105284.47357763315, 106185.0, 108494.0, 110435.0, 110638.34042489209, 1082.6860210538855, 1126.5887950898027, 1128.4019469680918, 1140.8745038045809, 1155.2086820527966, 17544.0, 17820.91089544544, 17856.285748569913, 17891.0, 17968.730469043236, 48917.26624100713, 50004.749976249885, 50541.0, 52718.73640631772, 54771.0, 64535.35464645481, 65414.34585654273, 71148.64425677838, 71191.28808711846, 71267.0, 7521.022267105242, 7855.0, 7856.489328193649, 7879.763607091796, 7881.369490440866, 9457.479838608942, 11181.440927953407, 12211.084168687206, 12364.938175309066, 12592.244465331743, 8636.524991125298, 10458.163346932379, 10654.57381704723, 10872.456377180952, 10955.851769692421, 9212.355135140744, 10446.42544660051, 10603.257771955889, 10745.623903163352, 10829.29609575375, 8353.20644538768, 10735.087517561095, 10840.45797710127, 11084.224104312621, 11172.441377930909, 7515.473916825988, 7556.526477337086, 7765.301122898966, 7780.260875216845, 7881.251281128284, 9284.442933424016, 10992.340459572451, 10999.780004399707, 11115.555377784798, 11218.38298893536], "unit": "score", "name": "jquery.html", "value": 9419.321548112526}, {"lowerIsBetter": false, "replicates": [1362.0, 1542.3290868472216, 1557.1046648177294, 1797.308036405984, 1936.041659378607, 7033.683484243208, 7930.9206907930975, 8694.39139260252, 8699.565021748911, 8746.562671866413, 8359.874601880976, 8628.741137765877, 8656.697015604455, 8667.826643467128, 8669.869951950726, 2123.617748805222, 2202.4603972026875, 2368.822338324624, 2419.2258477287232, 2443.58459061959, 2425.0905910283645, 2427.393151712072, 2428.0287884846025, 2432.4405386761055, 2435.1111844176926, 2163.729533808274, 2345.2495201535517, 2361.0791791201445, 2423.7455067217866, 2425.442148305891, 1848.6579982703242, 2130.7230060092243, 2186.387811412807, 2195.473086459246, 2196.615592271346, 2389.832711710181, 2452.1294940296293, 2458.299384675377, 2460.138951367025, 2462.273629279367, 8519.233269005757, 8602.698905538276, 8603.268722158624, 8612.224899759052, 8614.87077693835, 5272.156454967187, 5368.973155134199, 5375.086235340002, 5398.190271459273, 5405.459454054602, 3561.71506279497, 3569.4110471772387, 3583.3191693578137, 3665.9450108248398, 3673.0, 1044.0707770084555, 1052.6631477927067, 1060.9893901060968, 1074.4735079810914, 1637.6560922206352, 11752.588659396966, 11852.822207666892, 11867.109966752529, 11870.762584748256, 11876.228045177122, 4560.543945605413, 4661.627069834405, 4715.292706094045, 4728.621710263171, 4737.8104875805275, 9263.953680231556, 9267.25861931044, 9268.443893366419, 9276.814463710689, 9283.535823208813, 5330.280412144389, 5332.040232758063, 5343.3587969443915, 5362.70505122222, 5364.249005139283, 4896.094222568836, 5005.1491246488185, 5007.799688012512, 5015.724135172601, 5016.347874776256, 3569.37535931211, 3619.3304238715923, 3626.818659067036, 3641.5266015417824, 3697.6117507661306, 1141.8572678415198, 1144.6394385768456, 1146.822242552389, 1147.8852114788622, 1151.83874257604, 1082.0, 1116.1238427834112, 1116.5087361560886, 1116.6929094498914, 1283.8780315869978, 8072.757817265491, 8177.918220817715, 8217.096119426858, 8260.545669988089, 8261.339092872557, 492.79056401030135, 501.58869726824383, 503.97732102055494, 505.33800721055536, 506.5972551821311, 5039.7228152451235, 5125.615578831603, 5981.940180598225, 6344.746210151542, 6452.645104519296, 10380.844287335696, 10464.319819211727, 10652.84020739674, 10693.411862347646, 10697.572097116185, 10486.265961382782, 10578.047975682226, 10585.894141058645, 10596.417197054236, 10602.151827853753, 7964.0, 8137.75586732399, 8237.917620823715, 8240.175982401592, 8241.79395515117, 8092.87860682102, 8105.432619716799, 8114.026316842017, 8124.675012999413, 8148.5518296494265, 9403.764905877408, 9503.809923801624, 9759.707208783748, 9788.265880059167, 9806.362586431858, 9850.901490985285, 10094.545745441326, 10111.090001899864, 10111.241656875616, 10269.897301027193, 6973.616451095036, 6991.405730512964, 7080.610566418896, 7092.113485814273, 7103.467239956921, 7731.342835859015, 8022.197780221931, 8039.598020098854, 8196.0, 8262.917370826215, 7368.705251789868, 7514.774556763306, 7671.53970761756, 7776.222377762178, 7831.216878312123, 7458.03045604068, 7472.589007604634, 7547.207543207884, 7610.581418022062, 7629.427792915664, 7606.277403646644, 7616.619169041636, 7632.80917977055, 7642.541447513167, 7644.579548124907, 6895.758648447481, 6950.443964482728, 6955.374016338554, 6963.512554121163, 6965.582065076112, 6511.544191906521, 7000.089988301488, 7039.577625342496, 7113.679884405317, 7172.49792514519], "unit": "score", "name": "mootools.html", "value": 4737.396296491821}, {"lowerIsBetter": false, "replicates": [262.9631851540785, 324.65586478332955, 328.28925376559755, 329.1688486571408, 334.79744754423587, 6551.639659818708, 6582.605043697382, 6606.669666516679, 6610.537262391635, 6637.435817955467, 6199.225096862892, 6234.470070044052, 6390.712417941192, 6424.775132870351, 6496.025596160578, 4676.485586585472, 4738.076075165344, 4745.430548334205, 4751.809927602892, 4753.881152971168, 4738.9526104739025, 4744.098621261966, 4755.381800365947, 4758.547937945889, 4775.044991001796, 4512.12013657337, 4634.351190833286, 4671.509491503394, 4672.15901137795, 4673.813047478096, 4597.793099310539, 4736.928946065795, 4779.784909679072, 4783.306420568998, 4788.61691064714, 4068.186362727437, 4083.2650122977852, 4094.5086589609436, 4100.9952561622595, 4106.07613287008, 6220.502359811204, 6223.626582405071, 6225.377462253784, 6247.625142491465, 6260.686965651699, 5278.788848446057, 5480.588955828329, 5509.035918714249, 5520.282363292787, 5540.085885828834, 6875.415589674883, 6895.689693963784, 6905.102336696247, 6906.930930690679, 6926.861462770767, 4446.488653804821, 4453.77731113443, 4460.776961151962, 4462.44219472566, 4468.75421851798, 7140.178879428829, 7238.710451581983, 7308.671109800019, 7344.118705755237, 7357.227491113463, 4537.954620453819, 4540.614047805908, 4541.250693635513, 4543.159515489645, 4553.4518263789805, 4619.9076018479445, 4625.421822272216, 4625.676202665848, 4626.352310676508, 4640.095181439654, 5479.397266300703, 5481.972590137024, 5486.862828429321, 5491.203775452537, 5504.724763761876, 5944.524438044946, 6030.819075427744, 6674.532782705164, 6676.232233293234, 6972.1284839395075, 1580.217792192872, 1607.0919930239377, 1607.5900645335598, 1608.4290077022722, 1609.8390160984043, 1015.746063484129, 1024.114141267798, 1036.3937096798456, 1043.0612448795991, 1043.5617040842712, 6135.5398345124295, 6522.988936714722, 6525.575837570543, 6526.575772574767, 6527.5757075789925, 7163.534370265916, 7173.210946795848, 7181.138263408425, 7196.712131514785, 7208.675609597581, 6310.0850376695125, 6444.806655800334, 6503.642299673564, 6605.339466053452, 6635.535512514078, 3783.621637836194, 3805.7335986481235, 3813.7139714521522, 3830.3871380578976, 3840.327942610032, 6852.143482064742, 6925.0, 7343.302386273295, 7582.810429739301, 7583.938248645194, 7414.555126692416, 7418.629068546551, 7424.146223184295, 7425.0, 7429.7399591014055, 6398.456131228898, 7290.270972902667, 7522.924770752223, 7563.546187228784, 7578.507397019066, 5818.650880947157, 6988.720451181895, 7240.8913866290995, 7246.96376518114, 7264.34620884123, 7046.718131274692, 7107.431405487652, 7108.822279443055, 7117.252688467636, 7122.857542849218, 7545.849083018199, 7557.924420755722, 7560.924390756022, 7570.772876813704, 7575.545467271982, 4414.0, 4530.501644819002, 4549.067441174618, 4554.43069616298, 4559.931601025921, 6120.867639486709, 7173.0316407284345, 7176.102987126609, 7179.174394944648, 7188.101487314086, 6050.697465126638, 6943.062686537252, 6951.582905025714, 6973.058637084132, 6985.545939513915, 6067.666278354556, 6758.2903795102775, 6949.339812717783, 6967.860642787217, 6977.162740471176, 6791.185057793096, 6799.898001529882, 6802.387785099365, 6805.081314022742, 6816.829579260559, 6766.323367663194, 6927.203371612128, 6965.686544105426, 6975.6163411012885, 6980.511364204457, 6759.898601520883, 6819.761308354184, 6826.112605361272, 6826.11260536147, 6837.179538455417], "unit": "score", "name": "prototype.html", "value": 5040.812502093279}, {"lowerIsBetter": false, "replicates": [580.9709514524274, 641.611824845968, 661.8510835062109, 703.6305939381821, 732.3811379384418, 15061.62345941352, 15286.2356882156, 15292.541223763277, 15295.311710973005, 15307.311170997304, 13654.795178072336, 13822.377992990314, 13897.0, 13993.720125597481, 14028.0, 872.1191596487546, 887.40100432208, 889.4263200235844, 889.4929889962724, 892.0054139634293, 882.232457761745, 883.4169448164213, 886.8935727712684, 887.6405055952317, 892.6027917576681, 932.9346945713803, 933.1741408853146, 937.2455173585261, 938.3009657804911, 938.723076692374, 923.7690577355661, 927.4481683398367, 928.7956649537091, 931.4644079654192, 934.3599634250526, 688.215434404779, 688.7382794538044, 690.5545922879714, 690.6132565763189, 691.350130876979, 13318.334083295893, 13341.53304634343, 13359.666008349772, 13366.665833354244, 13371.53199638008, 10876.945615271952, 11054.778904421866, 11077.0, 11276.718082047932, 11543.653690389301, 9427.434353938786, 9513.714588562354, 9598.424094554279, 9606.855897161548, 9946.751331216705, 2936.3833594945086, 3436.7937923724407, 3451.0, 3497.108237399447, 3501.6323286054853, 80728.0, 81208.59395703043, 87996.0, 89220.55389723074, 90298.5485072577, 9496.90503094974, 9568.760780980392, 9595.28035397348, 9607.615695372248, 10151.94924025375, 20300.796992030188, 20880.895595521924, 20927.47681307949, 20940.0, 21016.684749728767, 7628.313451789366, 7703.191164927602, 7752.7674169775, 7763.573003484749, 7779.57212353326, 4226.154769046203, 4228.619424251832, 4249.0, 4277.294246449301, 4299.849505267301, 3494.7029502492574, 3533.0, 3548.077499850006, 3558.839852206657, 3562.07386079616, 648.6951132967498, 655.2638872391819, 658.4699317049725, 661.0414898397343, 670.3363669966698, 558.8407303918364, 619.1301221783401, 632.5319263744888, 634.1280738983897, 636.3636363636364, 8486.108958559385, 8574.099719529484, 8681.956590217009, 8745.048209881132, 8876.423032502867, 549.6647045302362, 562.8629572641056, 595.0479233226889, 595.7259660556193, 609.9024156134997, 3298.967010329866, 3946.6842652587725, 4031.5162180538523, 4059.8579049733116, 4082.0, 96577.51711241399, 100924.0, 101069.0, 101228.0, 101266.0, 66546.0, 73756.0, 78025.0, 85157.0, 85663.57168214119, 69665.0, 70884.93672594916, 71023.2897671027, 72179.63910180416, 76011.61994189993, 8884.822303553763, 8948.955255223682, 9053.728388148365, 9122.452652840851, 9181.77045573866, 50906.49093509166, 51172.74413627908, 51750.96498069942, 51865.0, 53794.65513362197, 57911.0, 68623.0, 68840.0, 71878.28121718924, 72336.0, 9264.490453025148, 10789.298695584763, 10833.945830270799, 10895.509702063267, 11047.889521104686, 11937.28376297425, 11945.701357466132, 11948.044156467635, 11953.163278570417, 11979.28124312544, 11348.432578371214, 11354.886451135382, 11370.0, 11377.431128443379, 11385.772284554429, 9497.002814704356, 10460.556723472117, 10885.782284354427, 10991.06575941054, 11030.558777648805, 10666.36001839892, 11246.66260012201, 11486.770264594828, 11523.769524609628, 11782.410879456163, 9399.154076133182, 9630.00709411173, 9656.082672146134, 9680.903190968, 10029.0, 9747.463889486145, 9887.258455615714, 9971.551280192263, 10008.699739007841, 10065.295429319878], "unit": "score", "name": "yui.html", "value": 6894.29289791135}], "name": "dromaeo_css", "value": 7431.913979074032}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 107.0, 110.0, 111.0, 109.0, 111.0, 115.0, 110.0, 110.0, 112.0], "unit": "score", "name": "ai-astar", "value": 110.7}, {"lowerIsBetter": true, "replicates": [157.0, 121.0, 144.0, 157.0, 122.0, 142.0, 141.0, 120.0, 168.0, 148.0], "unit": "score", "name": "audio-beat-detection", "value": 142.0}, {"lowerIsBetter": true, "replicates": [184.0, 183.0, 185.0, 183.0, 184.0, 185.0, 185.0, 185.0, 180.0, 180.0], "unit": "score", "name": "audio-dft", "value": 183.4}, {"lowerIsBetter": true, "replicates": [72.0, 81.0, 86.0, 77.0, 57.0, 58.0, 71.0, 79.0, 76.0, 56.0], "unit": "score", "name": "audio-fft", "value": 71.3}, {"lowerIsBetter": true, "replicates": [85.0, 98.0, 128.0, 78.0, 81.0, 84.0, 108.0, 77.0, 79.0, 110.0], "unit": "score", "name": "audio-oscillator", "value": 92.8}, {"lowerIsBetter": true, "replicates": [84.0, 90.0, 99.0, 140.0, 83.0, 90.0, 84.0, 91.0, 84.0, 89.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 93.4}, {"lowerIsBetter": true, "replicates": [108.0, 147.0, 93.0, 93.0, 108.0, 149.0, 93.0, 93.0, 93.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 106.9}, {"lowerIsBetter": true, "replicates": [85.0, 87.0, 86.0, 87.0, 85.0, 88.0, 88.0, 85.0, 88.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 86.6}, {"lowerIsBetter": true, "replicates": [57.0, 54.0, 55.0, 55.0, 55.0, 54.0, 55.0, 64.0, 52.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 55.4}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 72.0, 75.0, 70.0, 67.0, 66.0, 67.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.2}, {"lowerIsBetter": true, "replicates": [84.0, 83.0, 83.0, 96.0, 102.0, 81.0, 83.0, 83.0, 85.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.4}, {"lowerIsBetter": true, "replicates": [122.0, 127.0, 127.0, 124.0, 121.0, 121.0, 123.0, 125.0, 127.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.9}, {"lowerIsBetter": true, "replicates": [138.0, 128.0, 167.0, 168.0, 140.0, 137.0, 151.0, 139.0, 136.0, 171.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 147.5}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 65.0, 65.0, 67.0, 68.0, 67.0, 66.0, 67.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.5}], "name": "kraken", "value": 1435.0000000000002}]} 15:22:27 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1435.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7431.91"}}} 15:22:27 INFO - Return code: 0 15:22:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:22:27 INFO - # TBPL SUCCESS # 15:22:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:22:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:22:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:22:27 INFO - 2016-02-17 15:22:27.482 screenresolution[1691:9677] starting screenresolution argv=screenresolution get 15:22:27 INFO - 2016-02-17 15:22:27.485 screenresolution[1691:9677] Display 0: 1600x1200x32@60 15:22:27 INFO - 2016-02-17 15:22:27.491 screenresolution[1692:9680] starting screenresolution argv=screenresolution list 15:22:27 INFO - Available Modes on Display 0 15:22:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:22:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:22:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:22:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:22:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:22:27 INFO - Intel Iris: 15:22:27 INFO - Chipset Model: Intel Iris 15:22:27 INFO - Type: GPU 15:22:27 INFO - Bus: Built-In 15:22:27 INFO - VRAM (Dynamic, Max): 1536 MB 15:22:27 INFO - Vendor: Intel (0x8086) 15:22:27 INFO - Device ID: 0x0a2e 15:22:27 INFO - Revision ID: 0x0009 15:22:27 INFO - Displays: 15:22:27 INFO - Display: 15:22:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:22:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:22:27 INFO - Display Serial Number: 1600x1200 60 15:22:27 INFO - Main Display: Yes 15:22:27 INFO - Mirror: Off 15:22:27 INFO - Online: Yes 15:22:27 INFO - Rotation: Supported 15:22:27 INFO - Return code: 0 15:22:27 INFO - Running post-action listener: _resource_record_post_action 15:22:27 INFO - Running post-run listener: _resource_record_post_run 15:22:28 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 38778368; Write bytes: 706898944; Read time: 418; Write time: 1951 15:22:28 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404963840; Write bytes: 405604352; Read time: 30291; Write time: 687 15:22:28 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 36963840; Write bytes: 298749440; Read time: 399; Write time: 1227 15:22:28 INFO - Running post-run listener: _upload_blobber_files 15:22:28 INFO - Blob upload gear active. 15:22:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:22:28 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 15:22:28 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'] 15:22:28 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 15:22:29 INFO - (blobuploader) - INFO - Open directory for files ... 15:22:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:22:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:22:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:29 INFO - (blobuploader) - INFO - Done attempting. 15:22:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:22:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:22:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:31 INFO - (blobuploader) - INFO - Done attempting. 15:22:31 INFO - (blobuploader) - INFO - Iteration through files over. 15:22:31 INFO - Return code: 0 15:22:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:22:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:22:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6058e73f2d8fafc2b9b67cbea839082f9f50f36550aa9bb02a97ed37ac27af480c161222541d5cc553616a121793073fcd5a2c6d76266e50d403af71f814af0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/425de6bb02e539a686dbff35027a665beb13fddc5fcdbef145bec541fe6e05311306872262480038d215d7fd2bc55e1942e4ce22dc7e29d52229c64a63392769"} 15:22:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:22:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:22:31 INFO - Contents: 15:22:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6058e73f2d8fafc2b9b67cbea839082f9f50f36550aa9bb02a97ed37ac27af480c161222541d5cc553616a121793073fcd5a2c6d76266e50d403af71f814af0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/425de6bb02e539a686dbff35027a665beb13fddc5fcdbef145bec541fe6e05311306872262480038d215d7fd2bc55e1942e4ce22dc7e29d52229c64a63392769"} 15:22:31 INFO - Running post-run listener: copy_logs_to_upload_dir 15:22:31 INFO - Copying logs to upload dir... 15:22:31 INFO - mkdir: /builds/slave/test/build/upload/logs 15:22:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.951397 ========= master_lag: 0.59 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-02-17 15:22:31.622352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-02-17 15:22:31.626712) ========= 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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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/6058e73f2d8fafc2b9b67cbea839082f9f50f36550aa9bb02a97ed37ac27af480c161222541d5cc553616a121793073fcd5a2c6d76266e50d403af71f814af0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/425de6bb02e539a686dbff35027a665beb13fddc5fcdbef145bec541fe6e05311306872262480038d215d7fd2bc55e1942e4ce22dc7e29d52229c64a63392769"} build_url:https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011088 build_url: 'https://queue.taskcluster.net/v1/task/L25wVmdzRh2RFY_VSM4LKg/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6058e73f2d8fafc2b9b67cbea839082f9f50f36550aa9bb02a97ed37ac27af480c161222541d5cc553616a121793073fcd5a2c6d76266e50d403af71f814af0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/425de6bb02e539a686dbff35027a665beb13fddc5fcdbef145bec541fe6e05311306872262480038d215d7fd2bc55e1942e4ce22dc7e29d52229c64a63392769"}' ========= master_lag: 1.19 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-02-17 15:22:32.832067) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-02-17 15:22:32.835533) ========= 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.U82C2LdLZ8/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.DYpeWsr4Op/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.005193 ========= master_lag: 5.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-02-17 15:22:37.919525) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 15:22:37.919831) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 15:22:38.682891) ========= ========= Total master_lag: 7.03 =========