builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0300 starttime: 1463156972.88 results: success (0) buildid: 20160513073835 builduid: 56397e5e982c44d29ede311779dcfd50 revision: c7942b0606ada54b06f6c241e1431e9c677a443f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:32.879290) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:32.879856) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:32.880155) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:32.898942) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:32.899253) ========= 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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-05-13 09:29:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 23.5M=0s 2016-05-13 09:29:33 (23.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.895919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:33.813548) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:33.813864) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CHrrItwiPR/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.P2R5tU5ZcC/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.020108 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:33.862003) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 09:29:33.862320) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c7942b0606ada54b06f6c241e1431e9c677a443f --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 c7942b0606ada54b06f6c241e1431e9c677a443f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CHrrItwiPR/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.P2R5tU5ZcC/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-05-13 09:29:33,899 truncating revision to first 12 chars 2016-05-13 09:29:33,899 Setting DEBUG logging. 2016-05-13 09:29:33,899 attempt 1/10 2016-05-13 09:29:33,899 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c7942b0606ad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 09:29:34,984 unpacking tar archive at: fx-team-c7942b0606ad/testing/mozharness/ program finished with exit code 0 elapsedTime=1.349637 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 09:29:35.228531) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:35.228873) ========= script_repo_revision: c7942b0606ada54b06f6c241e1431e9c677a443f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:35.229329) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:35.229704) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 09:29:35.245138) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-13 09:29:35.245448) ========= /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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:29:35 INFO - MultiFileLogger online at 20160513 09:29:35 in /builds/slave/test 09:29:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:29:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:29:35 INFO - {'append_to_log': False, 09:29:35 INFO - 'base_work_dir': '/builds/slave/test', 09:29:35 INFO - 'blob_upload_branch': 'Fx-Team', 09:29:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:29:35 INFO - 'branch': 'Fx-Team', 09:29:35 INFO - 'buildbot_json_path': 'buildprops.json', 09:29:35 INFO - 'config_files': ('talos/mac_config.py',), 09:29:35 INFO - 'default_actions': ('clobber', 09:29:35 INFO - 'read-buildbot-config', 09:29:35 INFO - 'download-and-extract', 09:29:35 INFO - 'populate-webroot', 09:29:35 INFO - 'create-virtualenv', 09:29:35 INFO - 'install', 09:29:35 INFO - 'run-tests'), 09:29:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:29:35 INFO - 'download_minidump_stackwalk': True, 09:29:35 INFO - 'download_symbols': 'ondemand', 09:29:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:29:35 INFO - 'tooltool.py': '/tools/tooltool.py', 09:29:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:29:35 INFO - '/tools/misc-python/virtualenv.py')}, 09:29:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:29:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:29:35 INFO - 'installer_path': 'installer.exe', 09:29:35 INFO - 'log_level': 'info', 09:29:35 INFO - 'log_name': 'talos', 09:29:35 INFO - 'log_to_console': True, 09:29:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:29:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:29:35 INFO - 'opt_config_files': (), 09:29:35 INFO - 'pip_index': False, 09:29:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:35 INFO - 'cmd': ('bash', 09:29:35 INFO - '-c', 09:29:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:35 INFO - 'enabled': True, 09:29:35 INFO - 'halt_on_failure': False, 09:29:35 INFO - 'name': 'check_screen_resolution'},), 09:29:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:35 INFO - 'cmd': ('bash', 09:29:35 INFO - '-c', 09:29:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:35 INFO - 'enabled': True, 09:29:35 INFO - 'halt_on_failure': False, 09:29:35 INFO - 'name': 'check_screen_resolution'},), 09:29:35 INFO - 'run_cmd_checks_enabled': True, 09:29:35 INFO - 'sps_profile': False, 09:29:35 INFO - 'sps_profile_interval': 0, 09:29:35 INFO - 'suite': 'dromaeojs', 09:29:35 INFO - 'system_bits': '32', 09:29:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:29:35 INFO - 'title': 't-yosemite-r7-0300', 09:29:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:29:35 INFO - 'use_talos_json': True, 09:29:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:29:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:29:35 INFO - 'work_dir': 'build'} 09:29:35 INFO - ##### 09:29:35 INFO - ##### Running clobber step. 09:29:35 INFO - ##### 09:29:35 INFO - Running pre-action listener: _resource_record_pre_action 09:29:35 INFO - Running main action method: clobber 09:29:35 INFO - rmtree: /builds/slave/test/build 09:29:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:29:36 INFO - Running post-action listener: _resource_record_post_action 09:29:36 INFO - ##### 09:29:36 INFO - ##### Finished clobber step (success) 09:29:36 INFO - ##### 09:29:36 INFO - ##### 09:29:36 INFO - ##### Running read-buildbot-config step. 09:29:36 INFO - ##### 09:29:36 INFO - Running pre-action listener: _resource_record_pre_action 09:29:36 INFO - Running main action method: read_buildbot_config 09:29:36 INFO - Using buildbot properties: 09:29:36 INFO - { 09:29:36 INFO - "project": "", 09:29:36 INFO - "product": "firefox", 09:29:36 INFO - "script_repo_revision": "production", 09:29:36 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:29:36 INFO - "repository": "", 09:29:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:29:36 INFO - "buildid": "20160513073835", 09:29:36 INFO - "pgo_build": "False", 09:29:36 INFO - "basedir": "/builds/slave/test", 09:29:36 INFO - "buildnumber": 13, 09:29:36 INFO - "slavename": "t-yosemite-r7-0300", 09:29:36 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:29:36 INFO - "platform": "yosemite_r7", 09:29:36 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:29:36 INFO - "branch": "fx-team", 09:29:36 INFO - "repo_path": "integration/fx-team", 09:29:36 INFO - "revision": "c7942b0606ada54b06f6c241e1431e9c677a443f", 09:29:36 INFO - "stage_platform": "macosx64", 09:29:36 INFO - "builduid": "56397e5e982c44d29ede311779dcfd50", 09:29:36 INFO - "slavebuilddir": "test" 09:29:36 INFO - } 09:29:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:29:36 INFO - Running post-action listener: _resource_record_post_action 09:29:36 INFO - ##### 09:29:36 INFO - ##### Finished read-buildbot-config step (success) 09:29:36 INFO - ##### 09:29:36 INFO - ##### 09:29:36 INFO - ##### Running download-and-extract step. 09:29:36 INFO - ##### 09:29:36 INFO - Running pre-action listener: _resource_record_pre_action 09:29:36 INFO - Running main action method: download_and_extract 09:29:36 INFO - mkdir: /builds/slave/test/build/tests 09:29: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')]} 09:29:36 INFO - https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:29:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:29:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:29:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:29:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 09:29:55 INFO - Downloaded 1199 bytes. 09:29:55 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:29:55 INFO - Using the following test package requirements: 09:29:55 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:29:55 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:29:55 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:29:55 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:29:55 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:29:55 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:29:55 INFO - u'jsshell-mac.zip'], 09:29:55 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:29:55 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:29:55 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:29:55 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:29:55 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:29:55 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:29:55 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:29:55 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:29:55 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:29:55 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:29:55 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:29:55 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:55 INFO - https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:29:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:29:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:29:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:30:03 INFO - Downloaded 18153905 bytes. 09:30:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:30:06 INFO - Return code: 0 09:30:06 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:30: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')]} 09:30:06 INFO - https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:30:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:30:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:30:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:30:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:30:07 INFO - Downloaded 18153905 bytes. 09:30:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:30:11 INFO - Return code: 0 09:30: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')]} 09:30:11 INFO - https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:30:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:30:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:30:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:30:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:30:12 INFO - Downloaded 10840239 bytes. 09:30:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:30:12 INFO - Return code: 0 09:30: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')]} 09:30:12 INFO - https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:30:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:30:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:30:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:30:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 09:30:15 INFO - Downloaded 110072541 bytes. 09:30:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:30:15 INFO - mkdir: /builds/slave/test/properties 09:30:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:30:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:30:15 INFO - Contents: 09:30:15 INFO - build_url:https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:30:16 INFO - Running post-action listener: _resource_record_post_action 09:30:16 INFO - Running post-action listener: set_extra_try_arguments 09:30:16 INFO - ##### 09:30:16 INFO - ##### Finished download-and-extract step (success) 09:30:16 INFO - ##### 09:30:16 INFO - ##### 09:30:16 INFO - ##### Running populate-webroot step. 09:30:16 INFO - ##### 09:30:16 INFO - Running pre-action listener: _resource_record_pre_action 09:30:16 INFO - Running main action method: populate_webroot 09:30:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:30:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:30:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:30:16 INFO - '--tppagecycles', 09:30:16 INFO - '7'], 09:30:16 INFO - 'tests': ['tsvgm']}}, 09:30:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:30:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:30:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:30:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:30:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:16 INFO - 'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:16 INFO - 'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['damp', 'tps']}, 09:30:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:16 INFO - 'tests': ['damp', 'tps']}, 09:30:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['dromaeo_dom']}, 09:30:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:30:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['a11yr', 09:30:16 INFO - 'ts_paint', 09:30:16 INFO - 'tpaint', 09:30:16 INFO - 'sessionrestore', 09:30:16 INFO - 'sessionrestore_no_auto_restore', 09:30:16 INFO - 'tabpaint']}, 09:30:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:30:16 INFO - 'ts_paint', 09:30:16 INFO - 'tpaint', 09:30:16 INFO - 'sessionrestore', 09:30:16 INFO - 'sessionrestore_no_auto_restore', 09:30:16 INFO - 'tabpaint']}, 09:30:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:30:16 INFO - 'ts_paint', 09:30:16 INFO - 'tpaint', 09:30:16 INFO - 'sessionrestore', 09:30:16 INFO - 'sessionrestore_no_auto_restore', 09:30:16 INFO - 'tabpaint']}, 09:30:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:30:16 INFO - 'ts_paint', 09:30:16 INFO - 'tpaint', 09:30:16 INFO - 'sessionrestore', 09:30:16 INFO - 'sessionrestore_no_auto_restore', 09:30:16 INFO - 'tabpaint']}, 09:30:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['a11yr', 09:30:16 INFO - 'ts_paint', 09:30:16 INFO - 'tpaint', 09:30:16 INFO - 'sessionrestore', 09:30:16 INFO - 'sessionrestore_no_auto_restore', 09:30:16 INFO - 'tabpaint']}, 09:30:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['a11yr', 09:30:16 INFO - 'ts_paint', 09:30:16 INFO - 'tpaint', 09:30:16 INFO - 'sessionrestore', 09:30:16 INFO - 'sessionrestore_no_auto_restore', 09:30:16 INFO - 'tabpaint']}, 09:30:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['tsvgx', 09:30:16 INFO - 'tsvgr_opacity', 09:30:16 INFO - 'tart', 09:30:16 INFO - 'tscrollx', 09:30:16 INFO - 'cart']}, 09:30:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:30:16 INFO - 'tsvgr_opacity', 09:30:16 INFO - 'tart', 09:30:16 INFO - 'tscrollx', 09:30:16 INFO - 'cart']}, 09:30:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:16 INFO - 'talos_options': ['--disable-e10s'], 09:30:16 INFO - 'tests': ['tp5o']}, 09:30:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:16 INFO - 'tests': ['tp5o']}, 09:30:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:16 INFO - 'talos_options': ['--disable-e10s', 09:30:16 INFO - '--xperf_path', 09:30:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:16 INFO - 'tests': ['tp5n']}, 09:30:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:16 INFO - 'talos_options': ['--xperf_path', 09:30:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:16 INFO - 'tests': ['tp5n']}}, 09:30:16 INFO - 'talos.zip': {'path': '', 09:30:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:30:16 INFO - Running post-action listener: _resource_record_post_action 09:30:16 INFO - ##### 09:30:16 INFO - ##### Finished populate-webroot step (success) 09:30:16 INFO - ##### 09:30:16 INFO - ##### 09:30:16 INFO - ##### Running create-virtualenv step. 09:30:16 INFO - ##### 09:30:16 INFO - Running pre-action listener: _resource_record_pre_action 09:30:16 INFO - Running main action method: create_virtualenv 09:30:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:30:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:30:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:30:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:30:16 INFO - Using real prefix '/tools/python27' 09:30:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:30:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:20 INFO - Installing pip.................done. 09:30:20 INFO - Return code: 0 09:30:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100de3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100de9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb439c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, '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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:20 INFO - 'HOME': '/Users/cltbld', 09:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:20 INFO - 'LOGNAME': 'cltbld', 09:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:20 INFO - 'MOZ_NO_REMOTE': '1', 09:30:20 INFO - 'NO_EM_RESTART': '1', 09:30:20 INFO - 'PAGER': '/bin/cat', 09:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:20 INFO - 'PWD': '/builds/slave/test', 09:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:20 INFO - 'SHELL': '/bin/bash', 09:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:30:20 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:20 INFO - 'USER': 'cltbld', 09:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:20 INFO - 'XPC_FLAGS': '0x0', 09:30:20 INFO - 'XPC_SERVICE_NAME': '0', 09:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:30:20 INFO - Cleaning up... 09:30:20 INFO - Return code: 0 09:30:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100de3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100de9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb439c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, '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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:20 INFO - 'HOME': '/Users/cltbld', 09:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:20 INFO - 'LOGNAME': 'cltbld', 09:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:20 INFO - 'MOZ_NO_REMOTE': '1', 09:30:20 INFO - 'NO_EM_RESTART': '1', 09:30:20 INFO - 'PAGER': '/bin/cat', 09:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:20 INFO - 'PWD': '/builds/slave/test', 09:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:20 INFO - 'SHELL': '/bin/bash', 09:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:30:20 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:20 INFO - 'USER': 'cltbld', 09:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:20 INFO - 'XPC_FLAGS': '0x0', 09:30:20 INFO - 'XPC_SERVICE_NAME': '0', 09:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:21 INFO - Downloading/unpacking psutil>=3.1.1 09:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:23 INFO - Installing collected packages: psutil 09:30:23 INFO - Running setup.py install for psutil 09:30:24 INFO - building 'psutil._psutil_osx' extension 09:30:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:30:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:30:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:30:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:30:24 INFO - building 'psutil._psutil_posix' extension 09:30:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:30:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:30:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:30:24 INFO - ^ 09:30:24 INFO - 1 warning generated. 09:30:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:30:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:24 INFO - Successfully installed psutil 09:30:24 INFO - Cleaning up... 09:30:24 INFO - Return code: 0 09:30:24 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 09:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100de3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100de9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb439c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, '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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 09:30:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 09:30:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:30:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:24 INFO - 'HOME': '/Users/cltbld', 09:30:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:24 INFO - 'LOGNAME': 'cltbld', 09:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:24 INFO - 'MOZ_NO_REMOTE': '1', 09:30:24 INFO - 'NO_EM_RESTART': '1', 09:30:24 INFO - 'PAGER': '/bin/cat', 09:30:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:24 INFO - 'PWD': '/builds/slave/test', 09:30:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:24 INFO - 'SHELL': '/bin/bash', 09:30:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:30:24 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:30:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:24 INFO - 'USER': 'cltbld', 09:30:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:24 INFO - 'XPC_FLAGS': '0x0', 09:30:24 INFO - 'XPC_SERVICE_NAME': '0', 09:30:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:25 INFO - Downloading/unpacking mozsystemmonitor==0.1 09:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:27 INFO - Downloading mozsystemmonitor-0.1.tar.gz 09:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 09:30:27 INFO - Installing collected packages: mozsystemmonitor 09:30:27 INFO - Running setup.py install for mozsystemmonitor 09:30:27 INFO - Successfully installed mozsystemmonitor 09:30:27 INFO - Cleaning up... 09:30:27 INFO - Return code: 0 09:30:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:27 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 0x100de3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100de9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb439c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, '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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:30:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:30:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:27 INFO - 'HOME': '/Users/cltbld', 09:30:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:27 INFO - 'LOGNAME': 'cltbld', 09:30:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:27 INFO - 'MOZ_NO_REMOTE': '1', 09:30:27 INFO - 'NO_EM_RESTART': '1', 09:30:27 INFO - 'PAGER': '/bin/cat', 09:30:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:27 INFO - 'PWD': '/builds/slave/test', 09:30:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:27 INFO - 'SHELL': '/bin/bash', 09:30:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:30:27 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:30:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:27 INFO - 'USER': 'cltbld', 09:30:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:27 INFO - 'XPC_FLAGS': '0x0', 09:30:27 INFO - 'XPC_SERVICE_NAME': '0', 09:30:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:30:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:31 INFO - Downloading docopt-0.6.1.tar.gz 09:30:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:30:31 INFO - Installing collected packages: blobuploader, requests, docopt 09:30:31 INFO - Running setup.py install for blobuploader 09:30:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:30:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:30:31 INFO - Running setup.py install for requests 09:30:32 INFO - Running setup.py install for docopt 09:30:32 INFO - Successfully installed blobuploader requests docopt 09:30:32 INFO - Cleaning up... 09:30:32 INFO - Return code: 0 09:30:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:32 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 0x100de3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100de9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb439c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, '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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:32 INFO - 'HOME': '/Users/cltbld', 09:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:32 INFO - 'LOGNAME': 'cltbld', 09:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:32 INFO - 'MOZ_NO_REMOTE': '1', 09:30:32 INFO - 'NO_EM_RESTART': '1', 09:30:32 INFO - 'PAGER': '/bin/cat', 09:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:32 INFO - 'PWD': '/builds/slave/test', 09:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:32 INFO - 'SHELL': '/bin/bash', 09:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:30:32 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:32 INFO - 'USER': 'cltbld', 09:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:32 INFO - 'XPC_FLAGS': '0x0', 09:30:32 INFO - 'XPC_SERVICE_NAME': '0', 09:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:32 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Tgyiai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:32 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-tujriJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-UaHWRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-lbbYCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-m5A1_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-NyYSkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-zCnzub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-PW8WgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-PBdrzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-KrRjHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Eeh6RX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:33 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-RQrPVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:34 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-_NBjum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:34 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-NO3uYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:34 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-YTg4o7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:34 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Od04PO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:34 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-jcgJjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:30:34 INFO - Running setup.py install for manifestparser 09:30:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:30:34 INFO - Running setup.py install for mozcrash 09:30:34 INFO - Running setup.py install for mozdebug 09:30:35 INFO - Running setup.py install for mozdevice 09:30:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:30:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:30:35 INFO - Running setup.py install for mozfile 09:30:35 INFO - Running setup.py install for mozhttpd 09:30:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:30:35 INFO - Running setup.py install for mozinfo 09:30:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:30:35 INFO - Running setup.py install for mozInstall 09:30:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:30:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:30:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:30:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:30:35 INFO - Running setup.py install for mozleak 09:30:35 INFO - Running setup.py install for mozlog 09:30:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:30:36 INFO - Running setup.py install for moznetwork 09:30:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:30:36 INFO - Running setup.py install for mozprocess 09:30:36 INFO - Running setup.py install for mozprofile 09:30:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:30:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:30:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:30:36 INFO - Running setup.py install for mozrunner 09:30:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:30:36 INFO - Running setup.py install for mozscreenshot 09:30:36 INFO - Running setup.py install for moztest 09:30:37 INFO - Running setup.py install for mozversion 09:30:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:30:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:30:37 INFO - Cleaning up... 09:30:37 INFO - Return code: 0 09:30:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:37 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 0x100de3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100de9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb439c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, '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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:30:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:37 INFO - 'HOME': '/Users/cltbld', 09:30:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:37 INFO - 'LOGNAME': 'cltbld', 09:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:37 INFO - 'MOZ_NO_REMOTE': '1', 09:30:37 INFO - 'NO_EM_RESTART': '1', 09:30:37 INFO - 'PAGER': '/bin/cat', 09:30:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:37 INFO - 'PWD': '/builds/slave/test', 09:30:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:37 INFO - 'SHELL': '/bin/bash', 09:30:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:30:37 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:30:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:37 INFO - 'USER': 'cltbld', 09:30:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:37 INFO - 'XPC_FLAGS': '0x0', 09:30:37 INFO - 'XPC_SERVICE_NAME': '0', 09:30:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:37 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-3Z5Nmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-IY7vKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-uPYAeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-jrWMUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-d86giE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-ZMJbHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-oWdMGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-NpaVu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-6lIiJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-gNilYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:38 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-zTi1KM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:39 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-wa5sxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:39 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-FCQUBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:39 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Kkq6yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:39 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-rR4d3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:39 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-iF4mXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:39 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-XvvCA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:42 INFO - Downloading blessings-1.6.tar.gz 09:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:30:42 INFO - Installing collected packages: blessings 09:30:42 INFO - Running setup.py install for blessings 09:30:42 INFO - Successfully installed blessings 09:30:42 INFO - Cleaning up... 09:30:42 INFO - Return code: 0 09:30:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:30:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:43 INFO - Reading from file tmpfile_stdout 09:30:43 INFO - Current package versions: 09:30:43 INFO - blessings == 1.6 09:30:43 INFO - blobuploader == 1.2.4 09:30:43 INFO - docopt == 0.6.1 09:30:43 INFO - manifestparser == 1.1 09:30:43 INFO - mozInstall == 1.12 09:30:43 INFO - mozcrash == 0.17 09:30:43 INFO - mozdebug == 0.1 09:30:43 INFO - mozdevice == 0.48 09:30:43 INFO - mozfile == 1.2 09:30:43 INFO - mozhttpd == 0.7 09:30:43 INFO - mozinfo == 0.9 09:30:43 INFO - mozleak == 0.1 09:30:43 INFO - mozlog == 3.2 09:30:43 INFO - moznetwork == 0.27 09:30:43 INFO - mozprocess == 0.22 09:30:43 INFO - mozprofile == 0.28 09:30:43 INFO - mozrunner == 6.11 09:30:43 INFO - mozscreenshot == 0.1 09:30:43 INFO - mozsystemmonitor == 0.1 09:30:43 INFO - moztest == 0.7 09:30:43 INFO - mozversion == 1.4 09:30:43 INFO - psutil == 3.1.1 09:30:43 INFO - requests == 1.2.3 09:30:43 INFO - wsgiref == 0.1.2 09:30:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:43 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 0x100de3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100de9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb439c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, '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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:30:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:43 INFO - 'HOME': '/Users/cltbld', 09:30:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:43 INFO - 'LOGNAME': 'cltbld', 09:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:43 INFO - 'MOZ_NO_REMOTE': '1', 09:30:43 INFO - 'NO_EM_RESTART': '1', 09:30:43 INFO - 'PAGER': '/bin/cat', 09:30:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:43 INFO - 'PWD': '/builds/slave/test', 09:30:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:43 INFO - 'SHELL': '/bin/bash', 09:30:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:30:43 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:30:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:43 INFO - 'USER': 'cltbld', 09:30:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:43 INFO - 'XPC_FLAGS': '0x0', 09:30:43 INFO - 'XPC_SERVICE_NAME': '0', 09:30:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:30:43 INFO - Cleaning up... 09:30:43 INFO - Return code: 0 09:30:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:43 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 0x100de3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100de9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb439c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, '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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:30:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:30:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:43 INFO - 'HOME': '/Users/cltbld', 09:30:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:43 INFO - 'LOGNAME': 'cltbld', 09:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:43 INFO - 'MOZ_NO_REMOTE': '1', 09:30:43 INFO - 'NO_EM_RESTART': '1', 09:30:43 INFO - 'PAGER': '/bin/cat', 09:30:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:43 INFO - 'PWD': '/builds/slave/test', 09:30:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:43 INFO - 'SHELL': '/bin/bash', 09:30:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:30:43 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:30:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:43 INFO - 'USER': 'cltbld', 09:30:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:43 INFO - 'XPC_FLAGS': '0x0', 09:30:43 INFO - 'XPC_SERVICE_NAME': '0', 09:30:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:43 INFO - Downloading/unpacking jsonschema 09:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:30:45 INFO - Downloading/unpacking functools32 (from jsonschema) 09:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:46 INFO - Downloading functools32-3.2.3-2.tar.gz 09:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:30:46 INFO - warning: no files found matching '*.txt' 09:30:46 INFO - no previously-included directories found matching 'build' 09:30:46 INFO - no previously-included directories found matching 'dist' 09:30:46 INFO - no previously-included directories found matching '.git*' 09:30:46 INFO - Installing collected packages: jsonschema, functools32 09:30:46 INFO - Running setup.py install for functools32 09:30:46 INFO - warning: no files found matching '*.txt' 09:30:46 INFO - no previously-included directories found matching 'build' 09:30:46 INFO - no previously-included directories found matching 'dist' 09:30:46 INFO - no previously-included directories found matching '.git*' 09:30:46 INFO - Successfully installed jsonschema functools32 09:30:46 INFO - Cleaning up... 09:30:46 INFO - Return code: 0 09:30:46 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:46 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 0x100de3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100de9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb439c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, '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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:46 INFO - 'HOME': '/Users/cltbld', 09:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:46 INFO - 'LOGNAME': 'cltbld', 09:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:46 INFO - 'MOZ_NO_REMOTE': '1', 09:30:46 INFO - 'NO_EM_RESTART': '1', 09:30:46 INFO - 'PAGER': '/bin/cat', 09:30:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:46 INFO - 'PWD': '/builds/slave/test', 09:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:46 INFO - 'SHELL': '/bin/bash', 09:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:30:46 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:46 INFO - 'USER': 'cltbld', 09:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:46 INFO - 'XPC_FLAGS': '0x0', 09:30:46 INFO - 'XPC_SERVICE_NAME': '0', 09:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:47 INFO - Downloading/unpacking flake8 09:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:30:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:30:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30: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 09:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:30:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:30:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:30:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:30:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:30:50 INFO - no previously-included directories found matching 'docs/_build' 09:30:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:30:50 INFO - Running setup.py install for pep8 09:30:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:30:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:30:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:30:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:30:51 INFO - no previously-included directories found matching 'docs/_build' 09:30:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:30:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:30:51 INFO - Cleaning up... 09:30:51 INFO - Return code: 0 09:30:51 INFO - Running post-action listener: _resource_record_post_action 09:30:51 INFO - Running post-action listener: _start_resource_monitoring 09:30:51 INFO - Starting resource monitoring. 09:30:51 INFO - ##### 09:30:51 INFO - ##### Finished create-virtualenv step (success) 09:30:51 INFO - ##### 09:30:51 INFO - ##### 09:30:51 INFO - ##### Running install step. 09:30:51 INFO - ##### 09:30:51 INFO - Running pre-action listener: _resource_record_pre_action 09:30:51 INFO - Running main action method: install 09:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:51 INFO - Reading from file tmpfile_stdout 09:30:51 INFO - Detecting whether we're running mozinstall >=1.0... 09:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:30:51 INFO - Reading from file tmpfile_stdout 09:30:51 INFO - Output received: 09:30:51 INFO - Usage: mozinstall [options] installer 09:30:51 INFO - Options: 09:30:51 INFO - -h, --help show this help message and exit 09:30:51 INFO - -d DEST, --destination=DEST 09:30:51 INFO - Directory to install application into. [default: 09:30:51 INFO - "/builds/slave/test"] 09:30:51 INFO - --app=APP Application being installed. [default: firefox] 09:30:51 INFO - mkdir: /builds/slave/test/build/application 09:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:31:25 INFO - Reading from file tmpfile_stdout 09:31:25 INFO - Output received: 09:31:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:31:25 INFO - Running post-action listener: _resource_record_post_action 09:31:25 INFO - ##### 09:31:25 INFO - ##### Finished install step (success) 09:31:25 INFO - ##### 09:31:25 INFO - ##### 09:31:25 INFO - ##### Running run-tests step. 09:31:25 INFO - ##### 09:31:25 INFO - Running pre-action listener: _resource_record_pre_action 09:31:25 INFO - Running main action method: run_tests 09:31:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:31:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:31:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:31:25 INFO - 2016-05-13 09:31:25.786 screenresolution[1931:14610] starting screenresolution argv=screenresolution get 09:31:25 INFO - 2016-05-13 09:31:25.788 screenresolution[1931:14610] Display 0: 1600x1200x32@60 09:31:25 INFO - 2016-05-13 09:31:25.795 screenresolution[1932:14612] starting screenresolution argv=screenresolution list 09:31:25 INFO - Available Modes on Display 0 09:31:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:31:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:31:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:31:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:31:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:31:26 INFO - Intel Iris: 09:31:26 INFO - Chipset Model: Intel Iris 09:31:26 INFO - Type: GPU 09:31:26 INFO - Bus: Built-In 09:31:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:31:26 INFO - Vendor: Intel (0x8086) 09:31:26 INFO - Device ID: 0x0a2e 09:31:26 INFO - Revision ID: 0x0009 09:31:26 INFO - Displays: 09:31:26 INFO - Display: 09:31:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:31:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:31:26 INFO - Display Serial Number: 1600x1200 60 09:31:26 INFO - Main Display: Yes 09:31:26 INFO - Mirror: Off 09:31:26 INFO - Online: Yes 09:31:26 INFO - Rotation: Supported 09:31:26 INFO - Return code: 0 09:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:31:26 INFO - Python 2.7.3 09:31:26 INFO - Return code: 0 09:31:26 INFO - grabbing minidump binary from tooltool 09:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:26 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87d30>, '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 0x100aabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c7eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:31:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:31:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:31:26 INFO - Calling ['/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'] with output_timeout 600 09:31:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:31:26 INFO - Return code: 0 09:31:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:31:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:31:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:31:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:31:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:31:27 INFO - Return code: 0 09:31:27 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/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:31:27 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/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0300 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CHrrItwiPR/Render', 09:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:27 INFO - 'HOME': '/Users/cltbld', 09:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:27 INFO - 'LOGNAME': 'cltbld', 09:31:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:31:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:27 INFO - 'MOZ_NO_REMOTE': '1', 09:31:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:31:27 INFO - 'NO_EM_RESTART': '1', 09:31:27 INFO - 'PAGER': '/bin/cat', 09:31:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:27 INFO - 'PWD': '/builds/slave/test', 09:31:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:27 INFO - 'SHELL': '/bin/bash', 09:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P2R5tU5ZcC/Listeners', 09:31:27 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 09:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:27 INFO - 'USER': 'cltbld', 09:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:27 INFO - 'XPC_FLAGS': '0x0', 09:31:27 INFO - 'XPC_SERVICE_NAME': '0', 09:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:27 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/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:31:27 INFO - mozversion application_buildid: 20160513073835 09:31:27 INFO - mozversion application_changeset: c7942b0606ada54b06f6c241e1431e9c677a443f 09:31:27 INFO - mozversion application_display_name: Nightly 09:31:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:31:27 INFO - mozversion application_name: Firefox 09:31:27 INFO - mozversion application_remotingname: firefox 09:31:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:31:27 INFO - mozversion application_vendor: Mozilla 09:31:27 INFO - mozversion application_version: 49.0a1 09:31:27 INFO - mozversion platform_buildid: 20160513073835 09:31:27 INFO - mozversion platform_changeset: c7942b0606ada54b06f6c241e1431e9c677a443f 09:31:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:31:27 INFO - mozversion platform_version: 49.0a1 09:31:27 INFO - using testdate: 1463157087 09:31:27 INFO - actual date: 1463157087 09:31:27 INFO - starting webserver on 'localhost:51210' 09:31:27 INFO - SUITE-START | Running 2 tests 09:31:27 INFO - TEST-START | dromaeo_css 09:31:27 INFO - operating with platform_type : mac_ 09:31:27 INFO - Initialising browser for dromaeo_css test... 09:31:27 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpsfU3XY/profile http://localhost:51210/getInfo.html) 09:31:29 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 09:31:29 INFO - PROCESS | 1950 | colorDepth:24 09:31:29 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 09:31:29 INFO - PROCESS | 1950 | __metrics 09:31:29 INFO - PROCESS | 1950 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:31:29 ERROR - PROCESS | 1950 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:31:30 INFO - TEST-INFO | 1950: exit 0 09:31:30 INFO - Browser initialized. 09:31:30 INFO - Running cycle 1/1 for dromaeo_css test... 09:31:30 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpsfU3XY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:42 INFO - PROCESS | 1952 | RSS: Main: 238981120 09:31:42 INFO - PROCESS | 1952 | 09:32:33 INFO - PROCESS | 1952 | 1463157153800 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:34:44 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:51210/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51210/tests/dromaeo/cssquery-ext.html) 09:34:45 INFO - PROCESS | 1952 | RSS: Main: 362434560 09:34:45 INFO - PROCESS | 1952 | 09:37:49 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:51210/tests/dromaeo/cssquery-ext.html (next: http://localhost:51210/tests/dromaeo/cssquery-jquery.html) 09:37:49 INFO - PROCESS | 1952 | RSS: Main: 366809088 09:37:49 INFO - PROCESS | 1952 | 09:40:51 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:51210/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51210/tests/dromaeo/cssquery-mootools.html) 09:40:52 INFO - PROCESS | 1952 | RSS: Main: 371163136 09:40:52 INFO - PROCESS | 1952 | 09:43:54 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:51210/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51210/tests/dromaeo/cssquery-prototype.html) 09:43:55 INFO - PROCESS | 1952 | RSS: Main: 362876928 09:43:55 INFO - PROCESS | 1952 | 09:46:58 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:51210/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51210/tests/dromaeo/cssquery-yui.html) 09:46:59 INFO - PROCESS | 1952 | RSS: Main: 334929920 09:46:59 INFO - PROCESS | 1952 | 09:50:02 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:51210/tests/dromaeo/cssquery-yui.html (next: http://localhost:51210/tests/dromaeo/cssquery-dojo.html) 09:50:02 INFO - PROCESS | 1952 | RSS: Main: 377532416 09:50:02 INFO - PROCESS | 1952 | 09:50:02 INFO - PROCESS | 1952 | __start_tp_report 09:50:02 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 09:50:02 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 09:50:02 INFO - PROCESS | 1952 | |i|pagename|runs| 09:50:02 INFO - PROCESS | 1952 | |0;dojo.html;2991.8803247870087;3433.82830858457;3447.0865220716505;3483.407820670485;3502.509648649188;5673.319201695802;5697.974364614368;5711.485966263035;5792.362840087592;5823.41765823418;5688.516476099537;5760.020796464601;5760.337561180466;5771.192033115357;5777.393373695764;5119.513646203604;5159.045576568328;5185.740712964355;5210.55710264626;5241.18761591952;5223.947760522403;5254.238135470355;5261.8947621047555;5262.342207224097;5264.842054738345;5148.2020286855595;5172.922406163911;5175.922361164586;5189.403218629868;5249.081410753103;5253.921191182116;5258.842234732964;5267.236250743659;5270.841874743725;5320.733963301858;5093.490650934877;5095.974520127413;5157.226416037587;5206.921896171561;5207.062728708869;5457.590680698963;5536.612437129403;5562.554995600342;5612.747426365823;5623.8594035149035;6262.686865656745;6291.087792270141;6294.213223347082;6296.9370306296805;6304.495640348807;6134.987697083701;6230.345813689542;6309.3690630937;6329.936700632981;6351.237851457855;5805.216295800097;5847.181394604759;5851.297844258674;5893.292804863444;5903.527717782567;6302.369763023753;6311.747530098744;6315;6316.842078948063;6321.146645202931;6034.909476357858;6111.105587097633;6125.908111378333;6178.505719542426;6202.472789812826;6308.526860485482;6309.495240380758;6311.337309582428;6325.430711236011;6332.841678958063;6216.2540495140875;6263.686815659199;6282.434580887742;6283.8114856554375;6294.0558916162945;5177.792888284502;6140.197513349931;6187.443130118311;6274.1843560336865;6298.779542715983;4889.731064791471;4915.827946021872;4919.335889654851;4921.753912304371;4928.531789479994;4386.670999674973;4391.5827996340295;4404.273294906305;4456.086502267028;4475.037866858641;5700.144978253295;5722.685252311079;5732.426757324234;5776.393478684678;5777.913331300034;6200.0699895016105;6253;6270.498360131179;6313.589616674902;6344;5699.316082070095;5703.058995265817;5713.628614140068;5771.134329850472;5771.740271687784;5409.242706021081;5423.674579525241;5433.320834895638;5439.238506609078;5445.482679145475;6224.37756224374;6274.1843560336865;6344.111824344595;6390.680465976682;6394.104825324365;6229.501639868709;6260;6278.623282603058;6287.182666253358;6332.461740752087;6330.841728956813;6337.809865704036;6338.239411270677;6346.968265158829;6351.618902865843;6108.51131909455;6124.350421260821;6261.467775239155;6272.121902933684;6312.43188113072;6213.471854892204;6304.211973503312;6310.368963103837;6325.177726895658;6327.873442531216;6106.145139680537;6122.112293717297;6135;6144.53915956314;6163.96918015425;5930.584859059997;5992.700364981821;6065.696715164312;6102.145699601971;6174.536909731698;5943.346231914573;5951.226340575698;5998.400159983967;6005.609635373687;6015.097735339734;6153.907691384722;6156.538259630456;6173.290071641819;6173.660448675366;6176.35148309421;5846.152307915243;5896.380880007709;5939.554533410098;5966.701664916824;6024.728887199999 09:50:02 INFO - PROCESS | 1952 | |1;ext.html;481.75430530429475;506.9036882992231;506.9847904562862;529.9110328275394;566.0603398358726;30676.846615766917;31493.21266968327;32287.83856080719;34973.82513087437;42784;37661.81169094158;44528.664140075765;44841.32738008932;44844;45100;9825.164860986824;10019.499025048719;10025.34835235711;10026.498675066254;10029.849552256723;8849.159329863653;9070.183683468487;11822.172447928648;12070.517179312817;12079.27524348542;11976.04191664669;12043.638690839245;12055;12056;12060;8908.821823563492;11742.882571174265;11944.64166075019;12022.458989345501;12038.277703337828;11553;11839.171258011942;11841.289522628584;11849.644510664693;11852.170348075639;40482.39276410856;40977;40984.38523422151;40984.795076024726;41062.38406423906;2983.5673827295136;3045.5279431688123;3203.4073696366245;3427.640097789746;3647.2705458908326;28903.42193156125;29390;29564.96522621698;29925.10224693241;30110.24724381886;2309.8614083155067;2666.4533770576986;2689.17980016095;2692.932676683079;2693.165118813174;324700;462508;465869;467721;468264;54851.451485484635;55223.723881380334;55253.723731381084;55308.44691553033;55313.1703024455;30773;36642.45036324457;36731.63268367336;37812;37839.62160378416;16992.490225293262;17083.658326833392;17231.966082035116;17252.568685782706;17257.39599114025;11801.88198118025;12032.81950770739;12062.577809776616;13026.934865325802;13131.409086591126;17472.301107955795;17597.032163231146;19437.125329360213;19568.463155581816;20339.491512712302;7052.858942821114;8169.591520424074;8170.305524030405;8185.877211841827;8193.918060819435;828.5401602110766;948.2271948362063;1117.4524483003433;1139.3107170162098;1184.8045072562932;30517;30956.22609434782;31591.84204078965;31853.681463185534;32443.572715777755;1078.4715489410132;1160.5183848702693;1169.7309618787726;1177.1642134084896;1179.0390831472323;14958;15104.697906041818;15206;15574.922125389527;15617.453389131326;657879.4212057914;658460;668779;670680;671137;57553.42446575564;57828.71085644545;57861;57970;58239;1338.2840180503383;1455.9999999999789;1478.8447213042691;1515.5529118909728;1516.5374560759074;29255.414891702472;29903;29912.55131173078;30041.849790750908;30775.846120769253;45302.54697452983;50903;51553;51685.22472162845;51738.482615173365;547898;555075;555162;555382;558482;22774.316770496913;23291.650625240298;25308;26281.948722051668;26294.86852565725;43269.78365108154;44370.334444982705;44776.77611612051;44852;45067.7746611278;32841.83579082089;36563;36569.08577285589;37010.62989370179;37804.62195378011;33046.339073218885;35673.46489802603;35693.82153089218;36412.81793591015;36617.45073823948;36233;36575.08562285964;37145;37205.62794372022;37235.627643723215;31441.371172576877;32772.34455310928;32892.50661240036;33012.66987330096;33139.33721325608;22402.887985559966;23944.281671549186;24074.87962560176;24587;24703.50592988166 09:50:02 INFO - PROCESS | 1952 | |2;jquery.html;387.4420833999041;392.7466783924369;392.7780803845826;397.3959581436219;415.94592702948614;20257.696134557977;20371.388858334238;21472.141114355447;21515.354539363823;21534.569308613838;20449.488762780937;20734.85958272298;21043.789562104375;21351.359459216208;21402.14391424341;6078.483328917047;6211.534134939875;6237.220347456568;6241.843953901166;6244.344343843899;5952.993224113582;6136.938630613704;6141.600795948271;6196.752129914798;6211.658358790265;6146.631202127886;6147.6618785966975;6164.167837341969;6171.783987560436;6178.443940045395;5849.824505264827;6135.141080248769;6137.938620613803;6145.262568491765;6147.323794382637;5325.4142044375085;5339.946600533984;5351.357837059578;5354.089804733205;5373.81191658294;25262.621060684105;26534.867325663443;27140.86429567859;27346;27369.86315068432;2105.589410065038;2201.3946164804647;2206.5145667953025;2220.655798351258;2272.4432514034165;9309.58106885185;10258.692239232834;15910.840891591051;15938.521844344572;16121.274542645491;1744.9040302783342;1760.4014635024153;1788.4098247578397;1804.5669039430572;1815.582416044304;120341;120789.39605302006;120971;121148;123695.76304236932;9324.393914395543;11802.350870702023;11845.407729613486;11861.46623401949;11964.82052769209;15244.466443674419;15322.540323790305;15338.233088345538;15346.462873799363;15347.92326038385;8766.60550275239;8928.330375221884;8991.505467199237;9052.637894484278;9091.909080909107;8818.911810881937;8825.02924678285;8830.77923051916;8949.060348663426;8957.955210224038;4552.044070745114;4657.74382408964;4681.267930865533;4689.812407503729;4769.66612337133;1924.5454409335277;1978.624061428324;1982.5935683184912;1984.2757393551537;1991.1327688129047;907.7503686486137;918.701422037827;939.9389039712397;942.7171848445439;963.643451922793;17779.73330400045;19283.807161928202;19763;19923;21213.57572848565;919.2140719684706;931.3108299858192;958.9184919281939;970.8689326940914;1030.1346868630387;10928.541846505348;10993.065589424989;11093;11707.765844683228;11792;626265;634107;636520;649355;650795;100505.94434612186;135834;137957;138952;141611.29194353963;1014.9188064954934;1037.2169012395593;1062.5696592879897;1068.732816795801;1070.5236169904474;18838;19257.037148142816;19319;19395.3211637592;19447.34697550724;37761.2447751049;51309;56940.71529642325;57740.4225957735;57875.710621446626;68603.3139668597;81439;81797.59101204456;82616;84284;6937.93062069393;7472.887906681295;7580.696772129274;8109.7161599343735;8160.5103693778565;9257.67598134062;10179;12366.319852407843;13289.601311960656;13492.325383730971;10609.946950265508;11176.441177941233;11244.325340479598;11318.170457385653;11557.133215009077;10857.40284284372;10936.45317734126;10960.671179864617;11202.271852329572;11301.604443844426;11029.062529685067;11623.88376116228;11631.534738610699;11700.122490813053;11743.530258789895;6998;7477.92522074794;7644.312011918954;7733.419993500397;8513.489190648581;7281.616492866338;8984.23633991118;10270.589176433157;11284.63727595007;11455.198136130392 09:50:02 INFO - PROCESS | 1952 | |3;mootools.html;1411.9011669183162;1639.6966561186177;1674;1905.0950798370766;2016.9697454538175;8114.229148230916;8926.285897128231;9157.908420915788;9180.173784359406;9373.859392109123;8859.246964008067;9104.36269461138;9111;9111.635534578625;9139.314551408637;2139.5506943541873;2216.656418255173;2370.608849539824;2396.5925792615253;2436.4517983453757;2449.7182823975204;2475.1708177760383;2484.1802205272215;2484.9378765530914;2485.751424857518;2375.2161786610377;2404.987975060122;2408.614621660535;2408.722996855358;2410.023940304171;2123.352377524854;2207.194374053475;2211;2212.037763572851;2395.556821987929;2080.729505164334;2362.751911049332;2448.375664205634;2454.9754502454925;2455.300239431753;9018.413803102778;9106.908930910738;9106.954465227698;9120.771980700536;9127.5436228189;4945.010997800454;5291.3650361956425;5454.481824226732;5463.153211252262;5470.5350045246205;3533.8233088345737;3568.590406789304;3713.6286371362917;3715.869944551928;3716.479692843004;1024.0936770957755;1035.6375268655986;1049.5591851422344;1050.2280823594651;1064.3454275620516;10522.947385263025;12302.81545776805;12307.876921230763;12321.93839030799;12446.3154526501;4806.927896081562;4810.7113573185725;4939.48135445773;4956.405231372259;4969.329140566049;10275.126614237724;10287;10299;10308;10311.020453056948;5506.669599824023;5512.283403157644;5512.834614961557;5516.420775818561;5516.503514683617;4505.43682039745;4650.023495066006;4831.710097394168;4958;4965.453800081988;3495.3358861816682;3967.0082479380153;4058.736182148151;4143.461350024478;4164.333706606928;1138.914581406398;1236.6380213791615;1284.4091717809724;1349.8987575931649;1358.65354334646;843.3000609494105;921.3826736086838;934.4626839567276;957.0477375011908;969.9195870147893;5839.474447299678;6013.879722405615;6105.32841387447;6130.754769809247;6214.471769899609;449.51452431374037;455.53990469625916;469.2023559948102;480.0566886068822;484.1334012118339;3960.0693836948294;3992.54085780139;4031.294523458383;4100.425940368351;4116.608922152391;10829.620963266249;11247;11253.887461125447;11280;11282.153838462149;10179.44013079273;10524.263301568817;10594.682159535072;10836.566537338576;11201.775964480665;8332.791680207922;8492.02341730709;8572.785680358042;8588.355873309527;8596;5988.520918326611;6096.634201947897;6121.265448146251;6172.814815555541;6196.938030619636;10313.020263074995;10499.580016799242;10512.106470950055;10512.316699414514;10531.842022369823;10171.542280597243;10181.134603558485;10735.624253151102;10757.193210509087;10759.946200268949;7111.288871112847;7353.522021068614;7613.6573854177805;7671.424643151898;7898.170692077249;8625.611847467004;8753.956230218808;8846.557672116498;8860.025397206431;8870.15733505316;7516.549007059594;7684.46208765381;7696.846063078595;7815.14033456308;7858;7471.178170401143;7549.849003019799;7575.280348366897;7750;7777.961110194413;7219.241979592067;7299.927000729925;7299.963500182465;7303.598302093435;7313.48805583604;4856.63575231852;5002.574781143643;5096.719680417465;5135.922961155511;5151.793928242828;4601.458511398719;5010.423801262901;5128.333316668809;5164.664296820694;5178.223266510054 09:50:02 INFO - PROCESS | 1952 | |4;prototype.html;281.48909728842136;297.82952994730715;302.23383722264066;303.6189582074496;308.44633882181483;4450.22121128802;4882.511748825124;5254.316938797956;5296.364436267643;5324.653897496665;4935.136351138545;4972.975135124319;5127.794888204477;5151.330327057487;5246.632735708502;3445.7078595526677;3506.298740251947;3970.64997900713;4023.1752490739327;4027.3556231003045;3527.7177825773874;3721.776693398391;3987.063040185554;4014.7189696721243;4015.6787457003516;3725.150260051063;3787.091098136455;3911.76529408235;3958.841646334143;3988.521377434712;3052.8931487397836;3790.3935370340614;3948.565657777642;3981.840726370942;3999.640032397084;3062.739667128297;3122.5628412022334;3522.049046757387;3522.682958533744;3525.7884526928465;4030.717849750519;4435.667324950641;4698.483166851678;4814.807407703687;5103.591712662978;3901.4147877818273;4309.35359696045;4615.469221039556;4669.995950870579;4676.158291507527;5529.30883639545;5569.969555632221;5581.944180558224;5593.32880054392;5605.561580712403;3225.951610725841;3769.321522126016;3837.1366442550484;3925.4866894129877;3952.4071389291835;5390.299261096072;5709;5748.338941021752;5772.913406298909;5805.883882322372;3432.597181972579;3759.5676497202626;3806.467094606757;3806.923861522754;3834.175652234783;3328.9334213315597;3667.954632929602;3753.9061523462133;3876.3604005339366;3932.5674175840636;3752.5684546277525;4222.894427639334;4566.70316429431;4648.2330415481065;4651.302304654261;5103;5516.393196748354;5539.861503462445;5549.361823390362;5557.416471270459;1160.9651710448532;1206.1666267273695;1313.1923866821835;1361.9921258269007;1403.8034675145489;891.6522556203086;915.455304094063;916.5966974531185;923.5243849417556;925.1765928323797;4534.45586529612;5140.434552199255;5147.6911385317;5192.532672059533;5231.555317797954;5572.3870374258795;5657.9434205658235;5663.603547751618;5671.461211184931;5701.258836351248;4456.68803183774;4555.817767289272;5033.7415646088475;5162.5611822994715;5179.2749015137915;2629.934251643686;2909.7672186225473;2947.469455498031;2957.54158105494;2965.2356847675596;5108.514691104339;5563.860903477364;5803.448672376117;5804.332501762267;5884.676342801102;5155.793768249303;5239.842804715865;5794.073992633693;5805.432533216008;5822.883542329129;4893.681910675795;5754.654720716687;5869.4717475427415;5871.178035075093;5875.706214689163;5248.640869843227;5464.732136163448;5630.85922851932;5638.577106716931;5675.77296908119;4947.925781113214;5487.612888393562;5559.388467268678;5567.359753628384;5588.552915766648;5100.413452452867;5241.0042092002395;5339.866503337447;5493.258410114583;5836.299644042742;2961.452131355706;3166.5250212467395;3172.9365412692077;3336.398528706186;3354.5135955286837;4543.68194226401;4731.432228132646;4749.643776716829;4749.928751068667;4755.482291822713;4013.698972577127;4202;4337.525241417933;4609.838655647036;4620.491745908014;4228.302330115496;4608.193566125848;4628.699134556243;4634.86095417138;4698.553637404441;4413.58070983256;4542.477615074176;4952.5542701157065;5000.824971125993;5068.569171620454;4214.831406743696;5390.4879036491475;5392.433794451526;5483.39394908279;5507.697076660822;4575.4738205105505;5327.227552005015;5357.410684824588;5375.274337964481;5414.404415514369 09:50:02 INFO - PROCESS | 1952 | |5;yui.html;679.908212391327;708.0229283588646;721.3291638775938;722.562849476067;747.8429529798743;14906.552803415902;15441.305141268642;15580.454684086058;15622.218889055544;15623.296951637189;14554.563363099098;14657.340419681113;14674.926625366885;14698.118112913233;14741.410343586243;724.6557885004607;743.1156923261328;912.1061359867334;933.8552524358736;943.0663642993455;903.421810041574;921.6037104045258;922.0088404964656;933.8459154239576;933.8879334479872;960.3229723045271;965.932384733069;971.9854202186973;972.6255391674185;974.133021610767;759.3469616130124;940.6425558287875;961.3126614470617;965.0301447045745;968.0706521739139;721.271515769077;722.4148439763808;722.5050840174497;723.9638018099126;724.8840185570336;14990.250487475583;15027.774583381259;15032.248387580577;15048.021878577858;15074.246287685572;12282.631521054383;12291.385430728427;12402.82967966268;12555.811662825065;12650.873491265062;9710.271729620306;10538.736531586695;10809.189310801721;11005.449727513593;11253.831192532118;3913.119548101683;3950.743201691881;3997.5003124609425;4039.7172197946156;4088.509378874554;89461.73767836254;90261;91568.08431915662;92450;93434;7087.291270872975;9446;9656.75858103539;9730.854037189449;9781.657641982496;21599.56800863974;21832.23587174441;22105.557888842133;22147.56376798551;22150.778492215195;7419.924111003874;7597.240275972469;7651.426143039294;7654.272844079804;7822.8435431291055;3958.2281455116554;4051.6961227908023;4071.6742660587674;4088.366303223005;4210.536840947493;2717.714639962815;3352.5306457096026;3482.982585087109;3493.4235851084286;3645.0340659725193;610.188449362347;611.8653896142841;616.4051690119056;620.3641267700643;636.7643971730397;491.4765774450199;522.3314157877923;531.9998403002331;534.9197620356978;626.2297374229723;7974;8242.711505097293;8253.876191857127;8264.793380165424;8337.458065225741;577.4831525784483;596.4631831351818;597.1460811040254;598.7305712429425;601.6720887116531;3864.3430616795213;3942.980285098556;4172.165566886635;4195.727277726938;4306.397104405386;109031;109083;109226;109733;111274.44362778134;88996.6650500243;93228;95893;96107.9865016873;96716.03283967212;65349.69300613961;66127.34681632901;67402;72788;72995.63502182561;8158.918410816053;8208.302294305051;8429.831403372022;8500.91499085026;8540;38276;45577;46573;48053.75973120112;49800.25299620436;59594.70202648959;61187.38812611817;64058.359416407104;64474.71050579056;64706.02940955795;11048.337099774039;11169.497372618414;11236.775264494829;11688.298702077902;12293.56972505958;12053.75892482163;12056.63830085099;12089.879101208875;12334.321612311409;12347.444365003776;11429.771404572028;11433.314001159957;11454.484548195185;11455.885441145816;11491.080713543064;11336.263142895685;11373.090152787592;11432.82850757256;11738.943495085483;12197.963173130029;9163.725088247364;11230.775384492428;11264.211505194886;11387.259828111146;11499;9151.222146117654;9578.760530986781;9779.217662587118;9801.313908026656;9920.355176913477;9301.395409298373;9562.474063926551;9646.951765241129;9648.372855764354;9686.467244301632 09:50:02 INFO - PROCESS | 1952 | __end_tp_report 09:50:02 INFO - PROCESS | 1952 | __start_cc_report 09:50:02 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,1266 09:50:02 INFO - PROCESS | 1952 | __end_cc_report 09:50:02 INFO - PROCESS | 1952 | __startTimestamp1463158202712__endTimestamp 09:50:02 INFO - PROCESS | 1952 | 09:50:02 INFO - PROCESS | 1952 | ------- Summary: start ------- 09:50:02 INFO - PROCESS | 1952 | Number of tests: 6 09:50:02 INFO - PROCESS | 1952 | 09:50:02 INFO - PROCESS | 1952 | [#0] dojo.html Cycles:165 Average:5763.15 Median:5966.70 stddev:651.98 (10.9%) stddev-sans-first:616.67 09:50:02 INFO - PROCESS | 1952 | Values: 2991.9 3433.8 3447.1 3483.4 3502.5 5673.3 5698.0 5711.5 5792.4 5823.4 5688.5 5760.0 5760.3 5771.2 5777.4 5119.5 5159.0 5185.7 5210.6 5241.2 5223.9 5254.2 5261.9 5262.3 5264.8 5148.2 5172.9 5175.9 5189.4 5249.1 5253.9 5258.8 5267.2 5270.8 5320.7 5093.5 5096.0 5157.2 5206.9 5207.1 5457.6 5536.6 5562.6 5612.7 5623.9 6262.7 6291.1 6294.2 6296.9 6304.5 6135.0 6230.3 6309.4 6329.9 6351.2 5805.2 5847.2 5851.3 5893.3 5903.5 6302.4 6311.7 6315.0 6316.8 6321.1 6034.9 6111.1 6125.9 6178.5 6202.5 6308.5 6309.5 6311.3 6325.4 6332.8 6216.3 6263.7 6282.4 6283.8 6294.1 5177.8 6140.2 6187.4 6274.2 6298.8 4889.7 4915.8 4919.3 4921.8 4928.5 4386.7 4391.6 4404.3 4456.1 4475.0 5700.1 5722.7 5732.4 5776.4 5777.9 6200.1 6253.0 6270.5 6313.6 6344.0 5699.3 5703.1 5713.6 5771.1 5771.7 5409.2 5423.7 5433.3 5439.2 5445.5 6224.4 6274.2 6344.1 6390.7 6394.1 6229.5 6260.0 6278.6 6287.2 6332.5 6330.8 6337.8 6338.2 6347.0 6351.6 6108.5 6124.4 6261.5 6272.1 6312.4 6213.5 6304.2 6310.4 6325.2 6327.9 6106.1 6122.1 6135.0 6144.5 6164.0 5930.6 5992.7 6065.7 6102.1 6174.5 5943.3 5951.2 5998.4 6005.6 6015.1 6153.9 6156.5 6173.3 6173.7 6176.4 5846.2 5896.4 5939.6 5966.7 6024.7 09:50:02 INFO - PROCESS | 1952 | 09:50:02 INFO - PROCESS | 1952 | [#1] ext.html Cycles:180 Average:67741.41 Median:32365.71 stddev:150187.58 (464.0%) stddev-sans-first:150523.99 09:50:02 INFO - PROCESS | 1952 | Values: 481.8 506.9 507.0 529.9 566.1 30676.8 31493.2 32287.8 34973.8 42784.0 37661.8 44528.7 44841.3 44844.0 45100.0 9825.2 10019.5 10025.3 10026.5 10029.8 8849.2 9070.2 11822.2 12070.5 12079.3 11976.0 12043.6 12055.0 12056.0 12060.0 8908.8 11742.9 11944.6 12022.5 12038.3 11553.0 11839.2 11841.3 11849.6 11852.2 40482.4 40977.0 40984.4 40984.8 41062.4 2983.6 3045.5 3203.4 3427.6 3647.3 28903.4 29390.0 29565.0 29925.1 30110.2 2309.9 2666.5 2689.2 2692.9 2693.2 324700.0 462508.0 465869.0 467721.0 468264.0 54851.5 55223.7 55253.7 55308.4 55313.2 30773.0 36642.5 36731.6 37812.0 37839.6 16992.5 17083.7 17232.0 17252.6 17257.4 11801.9 12032.8 12062.6 13026.9 13131.4 17472.3 17597.0 19437.1 19568.5 20339.5 7052.9 8169.6 8170.3 8185.9 8193.9 828.5 948.2 1117.5 1139.3 1184.8 30517.0 30956.2 31591.8 31853.7 32443.6 1078.5 1160.5 1169.7 1177.2 1179.0 14958.0 15104.7 15206.0 15574.9 15617.5 657879.4 658460.0 668779.0 670680.0 671137.0 57553.4 57828.7 57861.0 57970.0 58239.0 1338.3 1456.0 1478.8 1515.6 1516.5 29255.4 29903.0 29912.6 30041.8 30775.8 45302.5 50903.0 51553.0 51685.2 51738.5 547898.0 555075.0 555162.0 555382.0 558482.0 22774.3 23291.7 25308.0 26281.9 26294.9 43269.8 44370.3 44776.8 44852.0 45067.8 32841.8 36563.0 36569.1 37010.6 37804.6 33046.3 35673.5 35693.8 36412.8 36617.5 36233.0 36575.1 37145.0 37205.6 37235.6 31441.4 32772.3 32892.5 33012.7 33139.3 22402.9 23944.3 24074.9 24587.0 24703.5 09:50:02 INFO - PROCESS | 1952 | 09:50:02 INFO - PROCESS | 1952 | [#2] jquery.html Cycles:180 Average:36537.48 Median:13767.53 stddev:106537.49 (773.8%) stddev-sans-first:106801.78 09:50:02 INFO - PROCESS | 1952 | Values: 387.4 392.7 392.8 397.4 415.9 20257.7 20371.4 21472.1 21515.4 21534.6 20449.5 20734.9 21043.8 21351.4 21402.1 6078.5 6211.5 6237.2 6241.8 6244.3 5953.0 6136.9 6141.6 6196.8 6211.7 6146.6 6147.7 6164.2 6171.8 6178.4 5849.8 6135.1 6137.9 6145.3 6147.3 5325.4 5339.9 5351.4 5354.1 5373.8 25262.6 26534.9 27140.9 27346.0 27369.9 2105.6 2201.4 2206.5 2220.7 2272.4 9309.6 10258.7 15910.8 15938.5 16121.3 1744.9 1760.4 1788.4 1804.6 1815.6 120341.0 120789.4 120971.0 121148.0 123695.8 9324.4 11802.4 11845.4 11861.5 11964.8 15244.5 15322.5 15338.2 15346.5 15347.9 8766.6 8928.3 8991.5 9052.6 9091.9 8818.9 8825.0 8830.8 8949.1 8958.0 4552.0 4657.7 4681.3 4689.8 4769.7 1924.5 1978.6 1982.6 1984.3 1991.1 907.8 918.7 939.9 942.7 963.6 17779.7 19283.8 19763.0 19923.0 21213.6 919.2 931.3 958.9 970.9 1030.1 10928.5 10993.1 11093.0 11707.8 11792.0 626265.0 634107.0 636520.0 649355.0 650795.0 100505.9 135834.0 137957.0 138952.0 141611.3 1014.9 1037.2 1062.6 1068.7 1070.5 18838.0 19257.0 19319.0 19395.3 19447.3 37761.2 51309.0 56940.7 57740.4 57875.7 68603.3 81439.0 81797.6 82616.0 84284.0 6937.9 7472.9 7580.7 8109.7 8160.5 9257.7 10179.0 12366.3 13289.6 13492.3 10609.9 11176.4 11244.3 11318.2 11557.1 10857.4 10936.5 10960.7 11202.3 11301.6 11029.1 11623.9 11631.5 11700.1 11743.5 6998.0 7477.9 7644.3 7733.4 8513.5 7281.6 8984.2 10270.6 11284.6 11455.2 09:50:02 INFO - PROCESS | 1952 | 09:50:02 INFO - PROCESS | 1952 | [#3] mootools.html Cycles:180 Average:5870.18 Median:474.63 stddev:3351.73 (706.2%) stddev-sans-first:3344.38 09:50:02 INFO - PROCESS | 1952 | Values: 1411.9 1639.7 1674.0 1905.1 2017.0 8114.2 8926.3 9157.9 9180.2 9373.9 8859.2 9104.4 9111.0 9111.6 9139.3 2139.6 2216.7 2370.6 2396.6 2436.5 2449.7 2475.2 2484.2 2484.9 2485.8 2375.2 2405.0 2408.6 2408.7 2410.0 2123.4 2207.2 2211.0 2212.0 2395.6 2080.7 2362.8 2448.4 2455.0 2455.3 9018.4 9106.9 9107.0 9120.8 9127.5 4945.0 5291.4 5454.5 5463.2 5470.5 3533.8 3568.6 3713.6 3715.9 3716.5 1024.1 1035.6 1049.6 1050.2 1064.3 10522.9 12302.8 12307.9 12321.9 12446.3 4806.9 4810.7 4939.5 4956.4 4969.3 10275.1 10287.0 10299.0 10308.0 10311.0 5506.7 5512.3 5512.8 5516.4 5516.5 4505.4 4650.0 4831.7 4958.0 4965.5 3495.3 3967.0 4058.7 4143.5 4164.3 1138.9 1236.6 1284.4 1349.9 1358.7 843.3 921.4 934.5 957.0 969.9 5839.5 6013.9 6105.3 6130.8 6214.5 449.5 455.5 469.2 480.1 484.1 3960.1 3992.5 4031.3 4100.4 4116.6 10829.6 11247.0 11253.9 11280.0 11282.2 10179.4 10524.3 10594.7 10836.6 11201.8 8332.8 8492.0 8572.8 8588.4 8596.0 5988.5 6096.6 6121.3 6172.8 6196.9 10313.0 10499.6 10512.1 10512.3 10531.8 10171.5 10181.1 10735.6 10757.2 10759.9 7111.3 7353.5 7613.7 7671.4 7898.2 8625.6 8754.0 8846.6 8860.0 8870.2 7516.5 7684.5 7696.8 7815.1 7858.0 7471.2 7549.8 7575.3 7750.0 7778.0 7219.2 7299.9 7300.0 7303.6 7313.5 4856.6 5002.6 5096.7 5135.9 5151.8 4601.5 5010.4 5128.3 5164.7 5178.2 09:50:02 INFO - PROCESS | 1952 | 09:50:02 INFO - PROCESS | 1952 | [#4] prototype.html Cycles:180 Average:4340.97 Median:4698.52 stddev:1346.15 (28.7%) stddev-sans-first:1314.99 09:50:02 INFO - PROCESS | 1952 | Values: 281.5 297.8 302.2 303.6 308.4 4450.2 4882.5 5254.3 5296.4 5324.7 4935.1 4973.0 5127.8 5151.3 5246.6 3445.7 3506.3 3970.6 4023.2 4027.4 3527.7 3721.8 3987.1 4014.7 4015.7 3725.2 3787.1 3911.8 3958.8 3988.5 3052.9 3790.4 3948.6 3981.8 3999.6 3062.7 3122.6 3522.0 3522.7 3525.8 4030.7 4435.7 4698.5 4814.8 5103.6 3901.4 4309.4 4615.5 4670.0 4676.2 5529.3 5570.0 5581.9 5593.3 5605.6 3226.0 3769.3 3837.1 3925.5 3952.4 5390.3 5709.0 5748.3 5772.9 5805.9 3432.6 3759.6 3806.5 3806.9 3834.2 3328.9 3668.0 3753.9 3876.4 3932.6 3752.6 4222.9 4566.7 4648.2 4651.3 5103.0 5516.4 5539.9 5549.4 5557.4 1161.0 1206.2 1313.2 1362.0 1403.8 891.7 915.5 916.6 923.5 925.2 4534.5 5140.4 5147.7 5192.5 5231.6 5572.4 5657.9 5663.6 5671.5 5701.3 4456.7 4555.8 5033.7 5162.6 5179.3 2629.9 2909.8 2947.5 2957.5 2965.2 5108.5 5563.9 5803.4 5804.3 5884.7 5155.8 5239.8 5794.1 5805.4 5822.9 4893.7 5754.7 5869.5 5871.2 5875.7 5248.6 5464.7 5630.9 5638.6 5675.8 4947.9 5487.6 5559.4 5567.4 5588.6 5100.4 5241.0 5339.9 5493.3 5836.3 2961.5 3166.5 3172.9 3336.4 3354.5 4543.7 4731.4 4749.6 4749.9 4755.5 4013.7 4202.0 4337.5 4609.8 4620.5 4228.3 4608.2 4628.7 4634.9 4698.6 4413.6 4542.5 4952.6 5000.8 5068.6 4214.8 5390.5 5392.4 5483.4 5507.7 4575.5 5327.2 5357.4 5375.3 5414.4 09:50:02 INFO - PROCESS | 1952 | 09:50:02 INFO - PROCESS | 1952 | [#5] yui.html Cycles:180 Average:19318.35 Median:597.94 stddev:28595.89 (4782.4%) stddev-sans-first:28641.86 09:50:02 INFO - PROCESS | 1952 | Values: 679.9 708.0 721.3 722.6 747.8 14906.6 15441.3 15580.5 15622.2 15623.3 14554.6 14657.3 14674.9 14698.1 14741.4 724.7 743.1 912.1 933.9 943.1 903.4 921.6 922.0 933.8 933.9 960.3 965.9 972.0 972.6 974.1 759.3 940.6 961.3 965.0 968.1 721.3 722.4 722.5 724.0 724.9 14990.3 15027.8 15032.2 15048.0 15074.2 12282.6 12291.4 12402.8 12555.8 12650.9 9710.3 10538.7 10809.2 11005.4 11253.8 3913.1 3950.7 3997.5 4039.7 4088.5 89461.7 90261.0 91568.1 92450.0 93434.0 7087.3 9446.0 9656.8 9730.9 9781.7 21599.6 21832.2 22105.6 22147.6 22150.8 7419.9 7597.2 7651.4 7654.3 7822.8 3958.2 4051.7 4071.7 4088.4 4210.5 2717.7 3352.5 3483.0 3493.4 3645.0 610.2 611.9 616.4 620.4 636.8 491.5 522.3 532.0 534.9 626.2 7974.0 8242.7 8253.9 8264.8 8337.5 577.5 596.5 597.1 598.7 601.7 3864.3 3943.0 4172.2 4195.7 4306.4 109031.0 109083.0 109226.0 109733.0 111274.4 88996.7 93228.0 95893.0 96108.0 96716.0 65349.7 66127.3 67402.0 72788.0 72995.6 8158.9 8208.3 8429.8 8500.9 8540.0 38276.0 45577.0 46573.0 48053.8 49800.3 59594.7 61187.4 64058.4 64474.7 64706.0 11048.3 11169.5 11236.8 11688.3 12293.6 12053.8 12056.6 12089.9 12334.3 12347.4 11429.8 11433.3 11454.5 11455.9 11491.1 11336.3 11373.1 11432.8 11738.9 12198.0 9163.7 11230.8 11264.2 11387.3 11499.0 9151.2 9578.8 9779.2 9801.3 9920.4 9301.4 9562.5 9647.0 9648.4 9686.5 09:50:02 INFO - PROCESS | 1952 | -------- Summary: end -------- 09:50:02 INFO - PROCESS | 1952 | 09:50:03 INFO - TEST-INFO | 1952: exit 0 09:50:03 INFO - TEST-OK | dromaeo_css | took 1115809ms 09:50:03 INFO - TEST-START | kraken 09:50:03 INFO - operating with platform_type : mac_ 09:50:03 INFO - Initialising browser for kraken test... 09:50:03 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpmc1kN6/profile http://localhost:51210/getInfo.html) 09:50:05 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 09:50:05 INFO - PROCESS | 1957 | colorDepth:24 09:50:05 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 09:50:05 INFO - PROCESS | 1957 | __metrics 09:50:05 INFO - PROCESS | 1957 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:50:05 ERROR - PROCESS | 1957 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:50:06 INFO - TEST-INFO | 1957: exit 0 09:50:06 INFO - Browser initialized. 09:50:06 INFO - Running cycle 1/1 for kraken test... 09:50:06 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpmc1kN6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:18 INFO - PROCESS | 1959 | RSS: Main: 239341568 09:50:18 INFO - PROCESS | 1959 | 09:50:20 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51210/tests/kraken/driver.html?testName=audio-beat-detection) 09:50:21 INFO - PROCESS | 1959 | RSS: Main: 286928896 09:50:21 INFO - PROCESS | 1959 | 09:50:24 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51210/tests/kraken/driver.html?testName=audio-dft) 09:50:25 INFO - PROCESS | 1959 | RSS: Main: 296259584 09:50:25 INFO - PROCESS | 1959 | 09:50:28 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51210/tests/kraken/driver.html?testName=audio-fft) 09:50:29 INFO - PROCESS | 1959 | RSS: Main: 382185472 09:50:29 INFO - PROCESS | 1959 | 09:50:31 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51210/tests/kraken/driver.html?testName=audio-oscillator) 09:50:32 INFO - PROCESS | 1959 | RSS: Main: 284041216 09:50:32 INFO - PROCESS | 1959 | 09:50:34 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51210/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:50:35 INFO - PROCESS | 1959 | RSS: Main: 287981568 09:50:35 INFO - PROCESS | 1959 | 09:50:37 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51210/tests/kraken/driver.html?testName=imaging-darkroom) 09:50:38 INFO - PROCESS | 1959 | RSS: Main: 325435392 09:50:38 INFO - PROCESS | 1959 | 09:50:41 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51210/tests/kraken/driver.html?testName=imaging-desaturate) 09:50:42 INFO - PROCESS | 1959 | RSS: Main: 330543104 09:50:42 INFO - PROCESS | 1959 | 09:50:45 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51210/tests/kraken/driver.html?testName=json-parse-financial) 09:50:46 INFO - PROCESS | 1959 | RSS: Main: 328884224 09:50:46 INFO - PROCESS | 1959 | 09:50:47 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51210/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:50:48 INFO - PROCESS | 1959 | RSS: Main: 315674624 09:50:48 INFO - PROCESS | 1959 | 09:50:50 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51210/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:50:51 INFO - PROCESS | 1959 | RSS: Main: 322555904 09:50:51 INFO - PROCESS | 1959 | 09:50:53 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51210/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:50:54 INFO - PROCESS | 1959 | RSS: Main: 325210112 09:50:54 INFO - PROCESS | 1959 | 09:50:57 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51210/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:57 INFO - PROCESS | 1959 | RSS: Main: 329310208 09:50:57 INFO - PROCESS | 1959 | 09:51:00 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51210/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:51:01 INFO - PROCESS | 1959 | RSS: Main: 327090176 09:51:01 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:51210/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51210/tests/kraken/driver.html?testName=ai-astar) 09:51:03 INFO - PROCESS | 1959 | RSS: Main: 374153216 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | __start_tp_report 09:51:03 INFO - PROCESS | 1959 | _x_x_mozilla_page_load 09:51:03 INFO - PROCESS | 1959 | _x_x_mozilla_page_load_details 09:51:03 INFO - PROCESS | 1959 | |i|pagename|runs| 09:51:03 INFO - PROCESS | 1959 | |0;ai-astar;113;104;112;112;112;111;112;112;113;111 09:51:03 INFO - PROCESS | 1959 | |1;audio-beat-detection;171;147;141;199;119;142;140;118;170;118 09:51:03 INFO - PROCESS | 1959 | |2;audio-dft;187;184;186;185;184;182;184;184;184;187 09:51:03 INFO - PROCESS | 1959 | |3;audio-fft;80;77;57;124;58;57;48;54;118;77 09:51:03 INFO - PROCESS | 1959 | |4;audio-oscillator;94;96;134;79;83;78;102;78;79;102 09:51:03 INFO - PROCESS | 1959 | |5;imaging-gaussian-blur;91;87;91;87;90;87;90;88;90;85 09:51:03 INFO - PROCESS | 1959 | |6;imaging-darkroom;94;130;145;126;95;95;96;96;94;95 09:51:03 INFO - PROCESS | 1959 | |7;imaging-desaturate;125;141;83;86;83;85;86;83;84;86 09:51:03 INFO - PROCESS | 1959 | |8;json-parse-financial;58;56;57;52;56;56;56;118;58;57 09:51:03 INFO - PROCESS | 1959 | |9;json-stringify-tinderbox;63;66;75;75;71;70;67;67;69;69 09:51:03 INFO - PROCESS | 1959 | |10;stanford-crypto-aes;85;83;85;85;85;84;85;84;83;85 09:51:03 INFO - PROCESS | 1959 | |11;stanford-crypto-ccm;139;141;145;130;138;140;140;138;218;136 09:51:03 INFO - PROCESS | 1959 | |12;stanford-crypto-pbkdf2;167;164;166;166;166;165;162;214;209;156 09:51:03 INFO - PROCESS | 1959 | |13;stanford-crypto-sha256-iterative;66;66;69;81;79;77;80;76;79;65 09:51:03 INFO - PROCESS | 1959 | __end_tp_report 09:51:03 INFO - PROCESS | 1959 | __start_cc_report 09:51:03 INFO - PROCESS | 1959 | _x_x_mozilla_cycle_collect,2325 09:51:03 INFO - PROCESS | 1959 | __end_cc_report 09:51:03 INFO - PROCESS | 1959 | __startTimestamp1463158263664__endTimestamp 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | ------- Summary: start ------- 09:51:03 INFO - PROCESS | 1959 | Number of tests: 14 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.62 (2.3%) stddev-sans-first:2.69 09:51:03 INFO - PROCESS | 1959 | Values: 113.0 104.0 112.0 112.0 112.0 111.0 112.0 112.0 113.0 111.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#1] audio-beat-detection Cycles:10 Average:146.50 Median:144.50 stddev:26.63 (18.4%) stddev-sans-first:26.73 09:51:03 INFO - PROCESS | 1959 | Values: 171.0 147.0 141.0 199.0 119.0 142.0 140.0 118.0 170.0 118.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#2] audio-dft Cycles:10 Average:184.70 Median:184.50 stddev:1.57 (0.8%) stddev-sans-first:1.42 09:51:03 INFO - PROCESS | 1959 | Values: 187.0 184.0 186.0 185.0 184.0 182.0 184.0 184.0 184.0 187.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#3] audio-fft Cycles:10 Average:75.00 Median:57.50 stddev:26.65 (46.3%) stddev-sans-first:28.20 09:51:03 INFO - PROCESS | 1959 | Values: 80.0 77.0 57.0 124.0 58.0 57.0 48.0 54.0 118.0 77.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:79.00 stddev:17.61 (22.3%) stddev-sans-first:18.67 09:51:03 INFO - PROCESS | 1959 | Values: 94.0 96.0 134.0 79.0 83.0 78.0 102.0 78.0 79.0 102.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:90.00 stddev:2.07 (2.3%) stddev-sans-first:2.00 09:51:03 INFO - PROCESS | 1959 | Values: 91.0 87.0 91.0 87.0 90.0 87.0 90.0 88.0 90.0 85.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#6] imaging-darkroom Cycles:10 Average:106.60 Median:95.00 stddev:19.28 (20.3%) stddev-sans-first:19.90 09:51:03 INFO - PROCESS | 1959 | Values: 94.0 130.0 145.0 126.0 95.0 95.0 96.0 96.0 94.0 95.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:84.50 stddev:20.83 (24.7%) stddev-sans-first:18.88 09:51:03 INFO - PROCESS | 1959 | Values: 125.0 141.0 83.0 86.0 83.0 85.0 86.0 83.0 84.0 86.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:56.50 stddev:19.61 (34.7%) stddev-sans-first:20.73 09:51:03 INFO - PROCESS | 1959 | Values: 58.0 56.0 57.0 52.0 56.0 56.0 56.0 118.0 58.0 57.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.50 stddev:3.79 (5.5%) stddev-sans-first:3.30 09:51:03 INFO - PROCESS | 1959 | Values: 63.0 66.0 75.0 75.0 71.0 70.0 67.0 67.0 69.0 69.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:0.84 (1.0%) stddev-sans-first:0.87 09:51:03 INFO - PROCESS | 1959 | Values: 85.0 83.0 85.0 85.0 85.0 84.0 85.0 84.0 83.0 85.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:140.00 stddev:25.41 (18.2%) stddev-sans-first:26.81 09:51:03 INFO - PROCESS | 1959 | Values: 139.0 141.0 145.0 130.0 138.0 140.0 140.0 138.0 218.0 136.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:166.00 stddev:20.31 (12.2%) stddev-sans-first:21.41 09:51:03 INFO - PROCESS | 1959 | Values: 167.0 164.0 166.0 166.0 166.0 165.0 162.0 214.0 209.0 156.0 09:51:03 INFO - PROCESS | 1959 | 09:51:03 INFO - PROCESS | 1959 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:78.00 stddev:6.51 (8.3%) stddev-sans-first:6.26 09:51:03 INFO - PROCESS | 1959 | Values: 66.0 66.0 69.0 81.0 79.0 77.0 80.0 76.0 79.0 65.0 09:51:03 INFO - PROCESS | 1959 | -------- Summary: end -------- 09:51:03 INFO - PROCESS | 1959 | 09:51:04 INFO - TEST-INFO | 1959: exit 0 09:51:04 INFO - TEST-OK | kraken | took 61313ms 09:51:04 INFO - SUITE-END | took 1177s 09:51:04 INFO - Completed test suite (00:19:37) 09:51:04 INFO - javascript benchmark 09:51:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2991.8803247870087, 3433.82830858457, 3447.0865220716505, 3483.407820670485, 3502.509648649188, 5673.319201695802, 5697.974364614368, 5711.485966263035, 5792.362840087592, 5823.41765823418, 5688.516476099537, 5760.020796464601, 5760.337561180466, 5771.192033115357, 5777.393373695764, 5119.513646203604, 5159.045576568328, 5185.740712964355, 5210.55710264626, 5241.18761591952, 5223.947760522403, 5254.238135470355, 5261.8947621047555, 5262.342207224097, 5264.842054738345, 5148.2020286855595, 5172.922406163911, 5175.922361164586, 5189.403218629868, 5249.081410753103, 5253.921191182116, 5258.842234732964, 5267.236250743659, 5270.841874743725, 5320.733963301858, 5093.490650934877, 5095.974520127413, 5157.226416037587, 5206.921896171561, 5207.062728708869, 5457.590680698963, 5536.612437129403, 5562.554995600342, 5612.747426365823, 5623.8594035149035, 6262.686865656745, 6291.087792270141, 6294.213223347082, 6296.9370306296805, 6304.495640348807, 6134.987697083701, 6230.345813689542, 6309.3690630937, 6329.936700632981, 6351.237851457855, 5805.216295800097, 5847.181394604759, 5851.297844258674, 5893.292804863444, 5903.527717782567, 6302.369763023753, 6311.747530098744, 6315.0, 6316.842078948063, 6321.146645202931, 6034.909476357858, 6111.105587097633, 6125.908111378333, 6178.505719542426, 6202.472789812826, 6308.526860485482, 6309.495240380758, 6311.337309582428, 6325.430711236011, 6332.841678958063, 6216.2540495140875, 6263.686815659199, 6282.434580887742, 6283.8114856554375, 6294.0558916162945, 5177.792888284502, 6140.197513349931, 6187.443130118311, 6274.1843560336865, 6298.779542715983, 4889.731064791471, 4915.827946021872, 4919.335889654851, 4921.753912304371, 4928.531789479994, 4386.670999674973, 4391.5827996340295, 4404.273294906305, 4456.086502267028, 4475.037866858641, 5700.144978253295, 5722.685252311079, 5732.426757324234, 5776.393478684678, 5777.913331300034, 6200.0699895016105, 6253.0, 6270.498360131179, 6313.589616674902, 6344.0, 5699.316082070095, 5703.058995265817, 5713.628614140068, 5771.134329850472, 5771.740271687784, 5409.242706021081, 5423.674579525241, 5433.320834895638, 5439.238506609078, 5445.482679145475, 6224.37756224374, 6274.1843560336865, 6344.111824344595, 6390.680465976682, 6394.104825324365, 6229.501639868709, 6260.0, 6278.623282603058, 6287.182666253358, 6332.461740752087, 6330.841728956813, 6337.809865704036, 6338.239411270677, 6346.968265158829, 6351.618902865843, 6108.51131909455, 6124.350421260821, 6261.467775239155, 6272.121902933684, 6312.43188113072, 6213.471854892204, 6304.211973503312, 6310.368963103837, 6325.177726895658, 6327.873442531216, 6106.145139680537, 6122.112293717297, 6135.0, 6144.53915956314, 6163.96918015425, 5930.584859059997, 5992.700364981821, 6065.696715164312, 6102.145699601971, 6174.536909731698, 5943.346231914573, 5951.226340575698, 5998.400159983967, 6005.609635373687, 6015.097735339734, 6153.907691384722, 6156.538259630456, 6173.290071641819, 6173.660448675366, 6176.35148309421, 5846.152307915243, 5896.380880007709, 5939.554533410098, 5966.701664916824, 6024.728887199999], "value": 5720.401154954296, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.75430530429475, 506.9036882992231, 506.9847904562862, 529.9110328275394, 566.0603398358726, 30676.846615766917, 31493.21266968327, 32287.83856080719, 34973.82513087437, 42784.0, 37661.81169094158, 44528.664140075765, 44841.32738008932, 44844.0, 45100.0, 9825.164860986824, 10019.499025048719, 10025.34835235711, 10026.498675066254, 10029.849552256723, 8849.159329863653, 9070.183683468487, 11822.172447928648, 12070.517179312817, 12079.27524348542, 11976.04191664669, 12043.638690839245, 12055.0, 12056.0, 12060.0, 8908.821823563492, 11742.882571174265, 11944.64166075019, 12022.458989345501, 12038.277703337828, 11553.0, 11839.171258011942, 11841.289522628584, 11849.644510664693, 11852.170348075639, 40482.39276410856, 40977.0, 40984.38523422151, 40984.795076024726, 41062.38406423906, 2983.5673827295136, 3045.5279431688123, 3203.4073696366245, 3427.640097789746, 3647.2705458908326, 28903.42193156125, 29390.0, 29564.96522621698, 29925.10224693241, 30110.24724381886, 2309.8614083155067, 2666.4533770576986, 2689.17980016095, 2692.932676683079, 2693.165118813174, 324700.0, 462508.0, 465869.0, 467721.0, 468264.0, 54851.451485484635, 55223.723881380334, 55253.723731381084, 55308.44691553033, 55313.1703024455, 30773.0, 36642.45036324457, 36731.63268367336, 37812.0, 37839.62160378416, 16992.490225293262, 17083.658326833392, 17231.966082035116, 17252.568685782706, 17257.39599114025, 11801.88198118025, 12032.81950770739, 12062.577809776616, 13026.934865325802, 13131.409086591126, 17472.301107955795, 17597.032163231146, 19437.125329360213, 19568.463155581816, 20339.491512712302, 7052.858942821114, 8169.591520424074, 8170.305524030405, 8185.877211841827, 8193.918060819435, 828.5401602110766, 948.2271948362063, 1117.4524483003433, 1139.3107170162098, 1184.8045072562932, 30517.0, 30956.22609434782, 31591.84204078965, 31853.681463185534, 32443.572715777755, 1078.4715489410132, 1160.5183848702693, 1169.7309618787726, 1177.1642134084896, 1179.0390831472323, 14958.0, 15104.697906041818, 15206.0, 15574.922125389527, 15617.453389131326, 657879.4212057914, 658460.0, 668779.0, 670680.0, 671137.0, 57553.42446575564, 57828.71085644545, 57861.0, 57970.0, 58239.0, 1338.2840180503383, 1455.9999999999789, 1478.8447213042691, 1515.5529118909728, 1516.5374560759074, 29255.414891702472, 29903.0, 29912.55131173078, 30041.849790750908, 30775.846120769253, 45302.54697452983, 50903.0, 51553.0, 51685.22472162845, 51738.482615173365, 547898.0, 555075.0, 555162.0, 555382.0, 558482.0, 22774.316770496913, 23291.650625240298, 25308.0, 26281.948722051668, 26294.86852565725, 43269.78365108154, 44370.334444982705, 44776.77611612051, 44852.0, 45067.7746611278, 32841.83579082089, 36563.0, 36569.08577285589, 37010.62989370179, 37804.62195378011, 33046.339073218885, 35673.46489802603, 35693.82153089218, 36412.81793591015, 36617.45073823948, 36233.0, 36575.08562285964, 37145.0, 37205.62794372022, 37235.627643723215, 31441.371172576877, 32772.34455310928, 32892.50661240036, 33012.66987330096, 33139.33721325608, 22402.887985559966, 23944.281671549186, 24074.87962560176, 24587.0, 24703.50592988166], "value": 19738.620450833452, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.4420833999041, 392.7466783924369, 392.7780803845826, 397.3959581436219, 415.94592702948614, 20257.696134557977, 20371.388858334238, 21472.141114355447, 21515.354539363823, 21534.569308613838, 20449.488762780937, 20734.85958272298, 21043.789562104375, 21351.359459216208, 21402.14391424341, 6078.483328917047, 6211.534134939875, 6237.220347456568, 6241.843953901166, 6244.344343843899, 5952.993224113582, 6136.938630613704, 6141.600795948271, 6196.752129914798, 6211.658358790265, 6146.631202127886, 6147.6618785966975, 6164.167837341969, 6171.783987560436, 6178.443940045395, 5849.824505264827, 6135.141080248769, 6137.938620613803, 6145.262568491765, 6147.323794382637, 5325.4142044375085, 5339.946600533984, 5351.357837059578, 5354.089804733205, 5373.81191658294, 25262.621060684105, 26534.867325663443, 27140.86429567859, 27346.0, 27369.86315068432, 2105.589410065038, 2201.3946164804647, 2206.5145667953025, 2220.655798351258, 2272.4432514034165, 9309.58106885185, 10258.692239232834, 15910.840891591051, 15938.521844344572, 16121.274542645491, 1744.9040302783342, 1760.4014635024153, 1788.4098247578397, 1804.5669039430572, 1815.582416044304, 120341.0, 120789.39605302006, 120971.0, 121148.0, 123695.76304236932, 9324.393914395543, 11802.350870702023, 11845.407729613486, 11861.46623401949, 11964.82052769209, 15244.466443674419, 15322.540323790305, 15338.233088345538, 15346.462873799363, 15347.92326038385, 8766.60550275239, 8928.330375221884, 8991.505467199237, 9052.637894484278, 9091.909080909107, 8818.911810881937, 8825.02924678285, 8830.77923051916, 8949.060348663426, 8957.955210224038, 4552.044070745114, 4657.74382408964, 4681.267930865533, 4689.812407503729, 4769.66612337133, 1924.5454409335277, 1978.624061428324, 1982.5935683184912, 1984.2757393551537, 1991.1327688129047, 907.7503686486137, 918.701422037827, 939.9389039712397, 942.7171848445439, 963.643451922793, 17779.73330400045, 19283.807161928202, 19763.0, 19923.0, 21213.57572848565, 919.2140719684706, 931.3108299858192, 958.9184919281939, 970.8689326940914, 1030.1346868630387, 10928.541846505348, 10993.065589424989, 11093.0, 11707.765844683228, 11792.0, 626265.0, 634107.0, 636520.0, 649355.0, 650795.0, 100505.94434612186, 135834.0, 137957.0, 138952.0, 141611.29194353963, 1014.9188064954934, 1037.2169012395593, 1062.5696592879897, 1068.732816795801, 1070.5236169904474, 18838.0, 19257.037148142816, 19319.0, 19395.3211637592, 19447.34697550724, 37761.2447751049, 51309.0, 56940.71529642325, 57740.4225957735, 57875.710621446626, 68603.3139668597, 81439.0, 81797.59101204456, 82616.0, 84284.0, 6937.93062069393, 7472.887906681295, 7580.696772129274, 8109.7161599343735, 8160.5103693778565, 9257.67598134062, 10179.0, 12366.319852407843, 13289.601311960656, 13492.325383730971, 10609.946950265508, 11176.441177941233, 11244.325340479598, 11318.170457385653, 11557.133215009077, 10857.40284284372, 10936.45317734126, 10960.671179864617, 11202.271852329572, 11301.604443844426, 11029.062529685067, 11623.88376116228, 11631.534738610699, 11700.122490813053, 11743.530258789895, 6998.0, 7477.92522074794, 7644.312011918954, 7733.419993500397, 8513.489190648581, 7281.616492866338, 8984.23633991118, 10270.589176433157, 11284.63727595007, 11455.198136130392], "value": 9749.918218391373, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.9011669183162, 1639.6966561186177, 1674.0, 1905.0950798370766, 2016.9697454538175, 8114.229148230916, 8926.285897128231, 9157.908420915788, 9180.173784359406, 9373.859392109123, 8859.246964008067, 9104.36269461138, 9111.0, 9111.635534578625, 9139.314551408637, 2139.5506943541873, 2216.656418255173, 2370.608849539824, 2396.5925792615253, 2436.4517983453757, 2449.7182823975204, 2475.1708177760383, 2484.1802205272215, 2484.9378765530914, 2485.751424857518, 2375.2161786610377, 2404.987975060122, 2408.614621660535, 2408.722996855358, 2410.023940304171, 2123.352377524854, 2207.194374053475, 2211.0, 2212.037763572851, 2395.556821987929, 2080.729505164334, 2362.751911049332, 2448.375664205634, 2454.9754502454925, 2455.300239431753, 9018.413803102778, 9106.908930910738, 9106.954465227698, 9120.771980700536, 9127.5436228189, 4945.010997800454, 5291.3650361956425, 5454.481824226732, 5463.153211252262, 5470.5350045246205, 3533.8233088345737, 3568.590406789304, 3713.6286371362917, 3715.869944551928, 3716.479692843004, 1024.0936770957755, 1035.6375268655986, 1049.5591851422344, 1050.2280823594651, 1064.3454275620516, 10522.947385263025, 12302.81545776805, 12307.876921230763, 12321.93839030799, 12446.3154526501, 4806.927896081562, 4810.7113573185725, 4939.48135445773, 4956.405231372259, 4969.329140566049, 10275.126614237724, 10287.0, 10299.0, 10308.0, 10311.020453056948, 5506.669599824023, 5512.283403157644, 5512.834614961557, 5516.420775818561, 5516.503514683617, 4505.43682039745, 4650.023495066006, 4831.710097394168, 4958.0, 4965.453800081988, 3495.3358861816682, 3967.0082479380153, 4058.736182148151, 4143.461350024478, 4164.333706606928, 1138.914581406398, 1236.6380213791615, 1284.4091717809724, 1349.8987575931649, 1358.65354334646, 843.3000609494105, 921.3826736086838, 934.4626839567276, 957.0477375011908, 969.9195870147893, 5839.474447299678, 6013.879722405615, 6105.32841387447, 6130.754769809247, 6214.471769899609, 449.51452431374037, 455.53990469625916, 469.2023559948102, 480.0566886068822, 484.1334012118339, 3960.0693836948294, 3992.54085780139, 4031.294523458383, 4100.425940368351, 4116.608922152391, 10829.620963266249, 11247.0, 11253.887461125447, 11280.0, 11282.153838462149, 10179.44013079273, 10524.263301568817, 10594.682159535072, 10836.566537338576, 11201.775964480665, 8332.791680207922, 8492.02341730709, 8572.785680358042, 8588.355873309527, 8596.0, 5988.520918326611, 6096.634201947897, 6121.265448146251, 6172.814815555541, 6196.938030619636, 10313.020263074995, 10499.580016799242, 10512.106470950055, 10512.316699414514, 10531.842022369823, 10171.542280597243, 10181.134603558485, 10735.624253151102, 10757.193210509087, 10759.946200268949, 7111.288871112847, 7353.522021068614, 7613.6573854177805, 7671.424643151898, 7898.170692077249, 8625.611847467004, 8753.956230218808, 8846.557672116498, 8860.025397206431, 8870.15733505316, 7516.549007059594, 7684.46208765381, 7696.846063078595, 7815.14033456308, 7858.0, 7471.178170401143, 7549.849003019799, 7575.280348366897, 7750.0, 7777.961110194413, 7219.241979592067, 7299.927000729925, 7299.963500182465, 7303.598302093435, 7313.48805583604, 4856.63575231852, 5002.574781143643, 5096.719680417465, 5135.922961155511, 5151.793928242828, 4601.458511398719, 5010.423801262901, 5128.333316668809, 5164.664296820694, 5178.223266510054], "value": 4611.126091918978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.48909728842136, 297.82952994730715, 302.23383722264066, 303.6189582074496, 308.44633882181483, 4450.22121128802, 4882.511748825124, 5254.316938797956, 5296.364436267643, 5324.653897496665, 4935.136351138545, 4972.975135124319, 5127.794888204477, 5151.330327057487, 5246.632735708502, 3445.7078595526677, 3506.298740251947, 3970.64997900713, 4023.1752490739327, 4027.3556231003045, 3527.7177825773874, 3721.776693398391, 3987.063040185554, 4014.7189696721243, 4015.6787457003516, 3725.150260051063, 3787.091098136455, 3911.76529408235, 3958.841646334143, 3988.521377434712, 3052.8931487397836, 3790.3935370340614, 3948.565657777642, 3981.840726370942, 3999.640032397084, 3062.739667128297, 3122.5628412022334, 3522.049046757387, 3522.682958533744, 3525.7884526928465, 4030.717849750519, 4435.667324950641, 4698.483166851678, 4814.807407703687, 5103.591712662978, 3901.4147877818273, 4309.35359696045, 4615.469221039556, 4669.995950870579, 4676.158291507527, 5529.30883639545, 5569.969555632221, 5581.944180558224, 5593.32880054392, 5605.561580712403, 3225.951610725841, 3769.321522126016, 3837.1366442550484, 3925.4866894129877, 3952.4071389291835, 5390.299261096072, 5709.0, 5748.338941021752, 5772.913406298909, 5805.883882322372, 3432.597181972579, 3759.5676497202626, 3806.467094606757, 3806.923861522754, 3834.175652234783, 3328.9334213315597, 3667.954632929602, 3753.9061523462133, 3876.3604005339366, 3932.5674175840636, 3752.5684546277525, 4222.894427639334, 4566.70316429431, 4648.2330415481065, 4651.302304654261, 5103.0, 5516.393196748354, 5539.861503462445, 5549.361823390362, 5557.416471270459, 1160.9651710448532, 1206.1666267273695, 1313.1923866821835, 1361.9921258269007, 1403.8034675145489, 891.6522556203086, 915.455304094063, 916.5966974531185, 923.5243849417556, 925.1765928323797, 4534.45586529612, 5140.434552199255, 5147.6911385317, 5192.532672059533, 5231.555317797954, 5572.3870374258795, 5657.9434205658235, 5663.603547751618, 5671.461211184931, 5701.258836351248, 4456.68803183774, 4555.817767289272, 5033.7415646088475, 5162.5611822994715, 5179.2749015137915, 2629.934251643686, 2909.7672186225473, 2947.469455498031, 2957.54158105494, 2965.2356847675596, 5108.514691104339, 5563.860903477364, 5803.448672376117, 5804.332501762267, 5884.676342801102, 5155.793768249303, 5239.842804715865, 5794.073992633693, 5805.432533216008, 5822.883542329129, 4893.681910675795, 5754.654720716687, 5869.4717475427415, 5871.178035075093, 5875.706214689163, 5248.640869843227, 5464.732136163448, 5630.85922851932, 5638.577106716931, 5675.77296908119, 4947.925781113214, 5487.612888393562, 5559.388467268678, 5567.359753628384, 5588.552915766648, 5100.413452452867, 5241.0042092002395, 5339.866503337447, 5493.258410114583, 5836.299644042742, 2961.452131355706, 3166.5250212467395, 3172.9365412692077, 3336.398528706186, 3354.5135955286837, 4543.68194226401, 4731.432228132646, 4749.643776716829, 4749.928751068667, 4755.482291822713, 4013.698972577127, 4202.0, 4337.525241417933, 4609.838655647036, 4620.491745908014, 4228.302330115496, 4608.193566125848, 4628.699134556243, 4634.86095417138, 4698.553637404441, 4413.58070983256, 4542.477615074176, 4952.5542701157065, 5000.824971125993, 5068.569171620454, 4214.831406743696, 5390.4879036491475, 5392.433794451526, 5483.39394908279, 5507.697076660822, 4575.4738205105505, 5327.227552005015, 5357.410684824588, 5375.274337964481, 5414.404415514369], "value": 3921.639377381209, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.908212391327, 708.0229283588646, 721.3291638775938, 722.562849476067, 747.8429529798743, 14906.552803415902, 15441.305141268642, 15580.454684086058, 15622.218889055544, 15623.296951637189, 14554.563363099098, 14657.340419681113, 14674.926625366885, 14698.118112913233, 14741.410343586243, 724.6557885004607, 743.1156923261328, 912.1061359867334, 933.8552524358736, 943.0663642993455, 903.421810041574, 921.6037104045258, 922.0088404964656, 933.8459154239576, 933.8879334479872, 960.3229723045271, 965.932384733069, 971.9854202186973, 972.6255391674185, 974.133021610767, 759.3469616130124, 940.6425558287875, 961.3126614470617, 965.0301447045745, 968.0706521739139, 721.271515769077, 722.4148439763808, 722.5050840174497, 723.9638018099126, 724.8840185570336, 14990.250487475583, 15027.774583381259, 15032.248387580577, 15048.021878577858, 15074.246287685572, 12282.631521054383, 12291.385430728427, 12402.82967966268, 12555.811662825065, 12650.873491265062, 9710.271729620306, 10538.736531586695, 10809.189310801721, 11005.449727513593, 11253.831192532118, 3913.119548101683, 3950.743201691881, 3997.5003124609425, 4039.7172197946156, 4088.509378874554, 89461.73767836254, 90261.0, 91568.08431915662, 92450.0, 93434.0, 7087.291270872975, 9446.0, 9656.75858103539, 9730.854037189449, 9781.657641982496, 21599.56800863974, 21832.23587174441, 22105.557888842133, 22147.56376798551, 22150.778492215195, 7419.924111003874, 7597.240275972469, 7651.426143039294, 7654.272844079804, 7822.8435431291055, 3958.2281455116554, 4051.6961227908023, 4071.6742660587674, 4088.366303223005, 4210.536840947493, 2717.714639962815, 3352.5306457096026, 3482.982585087109, 3493.4235851084286, 3645.0340659725193, 610.188449362347, 611.8653896142841, 616.4051690119056, 620.3641267700643, 636.7643971730397, 491.4765774450199, 522.3314157877923, 531.9998403002331, 534.9197620356978, 626.2297374229723, 7974.0, 8242.711505097293, 8253.876191857127, 8264.793380165424, 8337.458065225741, 577.4831525784483, 596.4631831351818, 597.1460811040254, 598.7305712429425, 601.6720887116531, 3864.3430616795213, 3942.980285098556, 4172.165566886635, 4195.727277726938, 4306.397104405386, 109031.0, 109083.0, 109226.0, 109733.0, 111274.44362778134, 88996.6650500243, 93228.0, 95893.0, 96107.9865016873, 96716.03283967212, 65349.69300613961, 66127.34681632901, 67402.0, 72788.0, 72995.63502182561, 8158.918410816053, 8208.302294305051, 8429.831403372022, 8500.91499085026, 8540.0, 38276.0, 45577.0, 46573.0, 48053.75973120112, 49800.25299620436, 59594.70202648959, 61187.38812611817, 64058.359416407104, 64474.71050579056, 64706.02940955795, 11048.337099774039, 11169.497372618414, 11236.775264494829, 11688.298702077902, 12293.56972505958, 12053.75892482163, 12056.63830085099, 12089.879101208875, 12334.321612311409, 12347.444365003776, 11429.771404572028, 11433.314001159957, 11454.484548195185, 11455.885441145816, 11491.080713543064, 11336.263142895685, 11373.090152787592, 11432.82850757256, 11738.943495085483, 12197.963173130029, 9163.725088247364, 11230.775384492428, 11264.211505194886, 11387.259828111146, 11499.0, 9151.222146117654, 9578.760530986781, 9779.217662587118, 9801.313908026656, 9920.355176913477, 9301.395409298373, 9562.474063926551, 9646.951765241129, 9648.372855764354, 9686.467244301632], "value": 7003.4168828262855, "unit": "score"}], "name": "dromaeo_css", "value": 7201.054036251665, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 104.0, 112.0, 112.0, 112.0, 111.0, 112.0, 112.0, 113.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 147.0, 141.0, 199.0, 119.0, 142.0, 140.0, 118.0, 170.0, 118.0], "value": 146.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 186.0, 185.0, 184.0, 182.0, 184.0, 184.0, 184.0, 187.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 57.0, 124.0, 58.0, 57.0, 48.0, 54.0, 118.0, 77.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 134.0, 79.0, 83.0, 78.0, 102.0, 78.0, 79.0, 102.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 91.0, 87.0, 90.0, 87.0, 90.0, 88.0, 90.0, 85.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 130.0, 145.0, 126.0, 95.0, 95.0, 96.0, 96.0, 94.0, 95.0], "value": 106.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 141.0, 83.0, 86.0, 83.0, 85.0, 86.0, 83.0, 84.0, 86.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 57.0, 52.0, 56.0, 56.0, 56.0, 118.0, 58.0, 57.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 75.0, 75.0, 71.0, 70.0, 67.0, 67.0, 69.0, 69.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 85.0, 85.0, 84.0, 85.0, 84.0, 83.0, 85.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 145.0, 130.0, 138.0, 140.0, 140.0, 138.0, 218.0, 136.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 166.0, 166.0, 166.0, 165.0, 162.0, 214.0, 209.0, 156.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 69.0, 81.0, 79.0, 77.0, 80.0, 76.0, 79.0, 65.0], "value": 73.8, "unit": "score"}], "name": "kraken", "value": 1509.1000000000001, "alertThreshold": 2.0}]} 09:51:04 INFO - Return code: 0 09:51:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:51:04 ERROR - # TBPL SUCCESS # 09:51:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:51:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:51:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:51:05 INFO - 2016-05-13 09:51:05.000 screenresolution[1963:16710] starting screenresolution argv=screenresolution get 09:51:05 INFO - 2016-05-13 09:51:05.003 screenresolution[1963:16710] Display 0: 1600x1200x32@60 09:51:05 INFO - 2016-05-13 09:51:05.009 screenresolution[1964:16712] starting screenresolution argv=screenresolution list 09:51:05 INFO - Available Modes on Display 0 09:51:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:51:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:51:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:51:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:51:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:51:05 INFO - Intel Iris: 09:51:05 INFO - Chipset Model: Intel Iris 09:51:05 INFO - Type: GPU 09:51:05 INFO - Bus: Built-In 09:51:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:51:05 INFO - Vendor: Intel (0x8086) 09:51:05 INFO - Device ID: 0x0a2e 09:51:05 INFO - Revision ID: 0x0009 09:51:05 INFO - Displays: 09:51:05 INFO - Display: 09:51:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:51:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:51:05 INFO - Display Serial Number: 1600x1200 60 09:51:05 INFO - Main Display: Yes 09:51:05 INFO - Mirror: Off 09:51:05 INFO - Online: Yes 09:51:05 INFO - Rotation: Supported 09:51:05 INFO - Return code: 0 09:51:05 INFO - Running post-action listener: _resource_record_post_action 09:51:05 INFO - ##### 09:51:05 INFO - ##### Finished run-tests step (success) 09:51:05 INFO - ##### 09:51:05 INFO - Running post-run listener: _resource_record_post_run 09:51:05 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 462848; Write bytes: 694809088; Read time: 7; Write time: 1893 09:51:05 INFO - TinderboxPrint: CPU usage
53.7% 09:51:05 INFO - TinderboxPrint: I/O read bytes / time
462,848 / 7 09:51:05 INFO - TinderboxPrint: I/O write bytes / time
694,809,088 / 1,893 09:51:05 INFO - TinderboxPrint: CPU idle
2,248.3 (46.3%) 09:51:05 INFO - TinderboxPrint: CPU system
966.0 (19.9%) 09:51:05 INFO - TinderboxPrint: CPU user
1,639.3 (33.8%) 09:51:05 INFO - TinderboxPrint: Swap in / out
394,694,656 / 0 09:51:05 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 384377344; Write bytes: 378865152; Read time: 29406; Write time: 757 09:51:05 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 462848; Write bytes: 296541184; Read time: 7; Write time: 1083 09:51:05 INFO - Running post-run listener: _upload_blobber_files 09:51:05 INFO - Blob upload gear active. 09:51:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:51:05 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 09:51:05 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'] 09:51:05 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 09:51:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:51:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:51:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:51:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:07 INFO - (blobuploader) - INFO - Done attempting. 09:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:51:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:08 INFO - (blobuploader) - INFO - Done attempting. 09:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:10 INFO - (blobuploader) - INFO - Done attempting. 09:51:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:51:10 INFO - Return code: 0 09:51:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:51:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:51:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1fefb36bfae041ef71e3f0583e7e394e73bf23b48c6801c93a916852f932f3377dae149c0328acd22f4f7ac92745eea1fc57a9dc5f65c72e720b5e4aa7221a6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a74a6396eb538a50680b4b018b8487f256b50db44cf753eacd24cce978ec1f57c86e29a94c82f992dbbfd5d7e7c962b981363f0333f1d3dad63a2c5af9b6002", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a777bcda0b1e85dde480be47d4f1185f45bdbb45d7ef754520d25632770b51181b6dd00c61ca72c12c294c0b8c8c29f463dc5025463a67938f09af0fd4106d0e"} 09:51:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:51:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:51:10 INFO - Contents: 09:51:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1fefb36bfae041ef71e3f0583e7e394e73bf23b48c6801c93a916852f932f3377dae149c0328acd22f4f7ac92745eea1fc57a9dc5f65c72e720b5e4aa7221a6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a74a6396eb538a50680b4b018b8487f256b50db44cf753eacd24cce978ec1f57c86e29a94c82f992dbbfd5d7e7c962b981363f0333f1d3dad63a2c5af9b6002", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a777bcda0b1e85dde480be47d4f1185f45bdbb45d7ef754520d25632770b51181b6dd00c61ca72c12c294c0b8c8c29f463dc5025463a67938f09af0fd4106d0e"} 09:51:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:51:10 INFO - Copying logs to upload dir... 09:51:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:51:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.694550 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-13 09:51:10.974235) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 09:51:10.978083) ========= 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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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/1fefb36bfae041ef71e3f0583e7e394e73bf23b48c6801c93a916852f932f3377dae149c0328acd22f4f7ac92745eea1fc57a9dc5f65c72e720b5e4aa7221a6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a74a6396eb538a50680b4b018b8487f256b50db44cf753eacd24cce978ec1f57c86e29a94c82f992dbbfd5d7e7c962b981363f0333f1d3dad63a2c5af9b6002", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a777bcda0b1e85dde480be47d4f1185f45bdbb45d7ef754520d25632770b51181b6dd00c61ca72c12c294c0b8c8c29f463dc5025463a67938f09af0fd4106d0e"} build_url:https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011010 build_url: 'https://queue.taskcluster.net/v1/task/DYwKlyyYTT-IV9XyJaEIOw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1fefb36bfae041ef71e3f0583e7e394e73bf23b48c6801c93a916852f932f3377dae149c0328acd22f4f7ac92745eea1fc57a9dc5f65c72e720b5e4aa7221a6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a74a6396eb538a50680b4b018b8487f256b50db44cf753eacd24cce978ec1f57c86e29a94c82f992dbbfd5d7e7c962b981363f0333f1d3dad63a2c5af9b6002", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a777bcda0b1e85dde480be47d4f1185f45bdbb45d7ef754520d25632770b51181b6dd00c61ca72c12c294c0b8c8c29f463dc5025463a67938f09af0fd4106d0e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 09:51:11.005803) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 09:51:11.006194) ========= 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.CHrrItwiPR/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.P2R5tU5ZcC/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.005164 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 09:51:11.055081) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 09:51:11.055451) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 09:51:11.055855) ========= ========= Total master_lag: 0.16 =========