builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0313 starttime: 1463640426.64 results: success (0) buildid: 20160518214151 builduid: 87bb5bad325047f9a40681931a6169f5 revision: aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:06.639318) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:06.639750) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:06.640041) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:06.689480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:06.689749) ========= 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.Friofd4ail/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.3kWM1gRTxD/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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-18 23:47:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 27.7M=0s 2016-05-18 23:47:06 (27.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.186094 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:06.893182) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:06.896519) ========= 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.Friofd4ail/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.3kWM1gRTxD/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.024865 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:06.944743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 23:47:06.945061) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 --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 aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Friofd4ail/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.3kWM1gRTxD/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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-18 23:47:07,026 truncating revision to first 12 chars 2016-05-18 23:47:07,026 Setting DEBUG logging. 2016-05-18 23:47:07,026 attempt 1/10 2016-05-18 23:47:07,026 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/aa7ea6cc0792?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 23:47:08,149 unpacking tar archive at: fx-team-aa7ea6cc0792/testing/mozharness/ program finished with exit code 0 elapsedTime=1.377234 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 23:47:08.345389) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:08.345662) ========= script_repo_revision: aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:08.346022) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:08.349301) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 23:47:08.362824) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-18 23:47:08.363147) ========= /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.Friofd4ail/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.3kWM1gRTxD/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:47:08 INFO - MultiFileLogger online at 20160518 23:47:08 in /builds/slave/test 23:47:08 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 23:47:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:47:08 INFO - {'append_to_log': False, 23:47:08 INFO - 'base_work_dir': '/builds/slave/test', 23:47:08 INFO - 'blob_upload_branch': 'Fx-Team', 23:47:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:47:08 INFO - 'branch': 'Fx-Team', 23:47:08 INFO - 'buildbot_json_path': 'buildprops.json', 23:47:08 INFO - 'config_files': ('talos/mac_config.py',), 23:47:08 INFO - 'default_actions': ('clobber', 23:47:08 INFO - 'read-buildbot-config', 23:47:08 INFO - 'download-and-extract', 23:47:08 INFO - 'populate-webroot', 23:47:08 INFO - 'create-virtualenv', 23:47:08 INFO - 'install', 23:47:08 INFO - 'run-tests'), 23:47:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:47:08 INFO - 'download_minidump_stackwalk': True, 23:47:08 INFO - 'download_symbols': 'ondemand', 23:47:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:47:08 INFO - 'tooltool.py': '/tools/tooltool.py', 23:47:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:47:08 INFO - '/tools/misc-python/virtualenv.py')}, 23:47:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:47:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:47:08 INFO - 'installer_path': 'installer.exe', 23:47:08 INFO - 'log_level': 'info', 23:47:08 INFO - 'log_name': 'talos', 23:47:08 INFO - 'log_to_console': True, 23:47:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:47:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:47:08 INFO - 'opt_config_files': (), 23:47:08 INFO - 'pip_index': False, 23:47:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:47:08 INFO - 'cmd': ('bash', 23:47:08 INFO - '-c', 23:47:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:47:08 INFO - 'enabled': True, 23:47:08 INFO - 'halt_on_failure': False, 23:47:08 INFO - 'name': 'check_screen_resolution'},), 23:47:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:47:08 INFO - 'cmd': ('bash', 23:47:08 INFO - '-c', 23:47:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:47:08 INFO - 'enabled': True, 23:47:08 INFO - 'halt_on_failure': False, 23:47:08 INFO - 'name': 'check_screen_resolution'},), 23:47:08 INFO - 'run_cmd_checks_enabled': True, 23:47:08 INFO - 'sps_profile': False, 23:47:08 INFO - 'sps_profile_interval': 0, 23:47:08 INFO - 'suite': 'dromaeojs', 23:47:08 INFO - 'system_bits': '32', 23:47:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:47:08 INFO - 'title': 't-yosemite-r7-0313', 23:47:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:47:08 INFO - 'use_talos_json': True, 23:47:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:47:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:47:08 INFO - 'work_dir': 'build'} 23:47:08 INFO - ##### 23:47:08 INFO - ##### Running clobber step. 23:47:08 INFO - ##### 23:47:08 INFO - Running pre-action listener: _resource_record_pre_action 23:47:08 INFO - Running main action method: clobber 23:47:08 INFO - rmtree: /builds/slave/test/build 23:47:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:47:10 INFO - Running post-action listener: _resource_record_post_action 23:47:10 INFO - ##### 23:47:10 INFO - ##### Finished clobber step (success) 23:47:10 INFO - ##### 23:47:10 INFO - ##### 23:47:10 INFO - ##### Running read-buildbot-config step. 23:47:10 INFO - ##### 23:47:10 INFO - Running pre-action listener: _resource_record_pre_action 23:47:10 INFO - Running main action method: read_buildbot_config 23:47:10 INFO - Using buildbot properties: 23:47:10 INFO - { 23:47:10 INFO - "project": "", 23:47:10 INFO - "product": "firefox", 23:47:10 INFO - "script_repo_revision": "production", 23:47:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 23:47:10 INFO - "repository": "", 23:47:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 23:47:10 INFO - "buildid": "20160518214151", 23:47:10 INFO - "pgo_build": "False", 23:47:10 INFO - "basedir": "/builds/slave/test", 23:47:10 INFO - "buildnumber": 823, 23:47:10 INFO - "slavename": "t-yosemite-r7-0313", 23:47:10 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 23:47:10 INFO - "platform": "yosemite_r7", 23:47:10 INFO - "scheduler": "tests-fx-team-macosx64-talos", 23:47:10 INFO - "branch": "fx-team", 23:47:10 INFO - "repo_path": "integration/fx-team", 23:47:10 INFO - "revision": "aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2", 23:47:10 INFO - "stage_platform": "macosx64", 23:47:10 INFO - "builduid": "87bb5bad325047f9a40681931a6169f5", 23:47:10 INFO - "slavebuilddir": "test" 23:47:10 INFO - } 23:47:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 23:47:10 INFO - Running post-action listener: _resource_record_post_action 23:47:10 INFO - ##### 23:47:10 INFO - ##### Finished read-buildbot-config step (success) 23:47:10 INFO - ##### 23:47:10 INFO - ##### 23:47:10 INFO - ##### Running download-and-extract step. 23:47:10 INFO - ##### 23:47:10 INFO - Running pre-action listener: _resource_record_pre_action 23:47:10 INFO - Running main action method: download_and_extract 23:47:10 INFO - mkdir: /builds/slave/test/build/tests 23:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:47:10 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:47:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 23:47:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 23:47:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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 23:47:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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 23:47:18 INFO - Downloaded 1199 bytes. 23:47:18 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 23:47:18 INFO - Using the following test package requirements: 23:47:18 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 23:47:18 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:47:18 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 23:47:18 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:47:18 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 23:47:18 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:47:18 INFO - u'jsshell-mac.zip'], 23:47:18 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:47:18 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 23:47:18 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 23:47:18 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:47:18 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 23:47:18 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:47:18 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 23:47:18 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:47:18 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 23:47:18 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:47:18 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 23:47:18 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:47:18 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')]} 23:47:18 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:47:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:47:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:47:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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 23:47:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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 23:47:31 INFO - Downloaded 18629060 bytes. 23:47:31 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'] 23:47:31 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 23:47:34 INFO - Return code: 0 23:47:34 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 23:47:34 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')]} 23:47:34 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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 23:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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 23:47:36 INFO - Downloaded 18629060 bytes. 23:47:36 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'] 23:47:36 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 23:47:40 INFO - Return code: 0 23:47:40 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')]} 23:47:40 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:47:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 23:47:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 23:47:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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 23:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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 23:47:41 INFO - Downloaded 10840194 bytes. 23:47:41 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'] 23:47:41 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 23:47:41 INFO - Return code: 0 23:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:47:41 INFO - https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 23:47:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:47:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:47:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 23:47:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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 23:47:46 INFO - Downloaded 110312343 bytes. 23:47:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:47:46 INFO - mkdir: /builds/slave/test/properties 23:47:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:47:46 INFO - Writing to file /builds/slave/test/properties/build_url 23:47:46 INFO - Contents: 23:47:46 INFO - build_url:https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:47:48 INFO - Running post-action listener: _resource_record_post_action 23:47:48 INFO - Running post-action listener: set_extra_try_arguments 23:47:48 INFO - ##### 23:47:48 INFO - ##### Finished download-and-extract step (success) 23:47:48 INFO - ##### 23:47:48 INFO - ##### 23:47:48 INFO - ##### Running populate-webroot step. 23:47:48 INFO - ##### 23:47:48 INFO - Running pre-action listener: _resource_record_pre_action 23:47:48 INFO - Running main action method: populate_webroot 23:47:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:47:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:47:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:47:48 INFO - '--tppagecycles', 23:47:48 INFO - '7'], 23:47:48 INFO - 'tests': ['tsvgm']}}, 23:47:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:47:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:47:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:47:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:47:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:47:48 INFO - 'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:47:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:47:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:47:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:47:48 INFO - 'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['damp', 'tps']}, 23:47:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:47:48 INFO - 'tests': ['damp', 'tps']}, 23:47:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['dromaeo_dom']}, 23:47:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:47:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['a11yr', 23:47:48 INFO - 'ts_paint', 23:47:48 INFO - 'tpaint', 23:47:48 INFO - 'sessionrestore', 23:47:48 INFO - 'sessionrestore_no_auto_restore', 23:47:48 INFO - 'tabpaint']}, 23:47:48 INFO - 'other-e10s': {'tests': ['a11yr', 23:47:48 INFO - 'ts_paint', 23:47:48 INFO - 'tpaint', 23:47:48 INFO - 'sessionrestore', 23:47:48 INFO - 'sessionrestore_no_auto_restore', 23:47:48 INFO - 'tabpaint']}, 23:47:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:47:48 INFO - 'ts_paint', 23:47:48 INFO - 'tpaint', 23:47:48 INFO - 'sessionrestore', 23:47:48 INFO - 'sessionrestore_no_auto_restore', 23:47:48 INFO - 'tabpaint']}, 23:47:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:47:48 INFO - 'ts_paint', 23:47:48 INFO - 'tpaint', 23:47:48 INFO - 'sessionrestore', 23:47:48 INFO - 'sessionrestore_no_auto_restore', 23:47:48 INFO - 'tabpaint']}, 23:47:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['a11yr', 23:47:48 INFO - 'ts_paint', 23:47:48 INFO - 'tpaint', 23:47:48 INFO - 'sessionrestore', 23:47:48 INFO - 'sessionrestore_no_auto_restore', 23:47:48 INFO - 'tabpaint']}, 23:47:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['a11yr', 23:47:48 INFO - 'ts_paint', 23:47:48 INFO - 'tpaint', 23:47:48 INFO - 'sessionrestore', 23:47:48 INFO - 'sessionrestore_no_auto_restore', 23:47:48 INFO - 'tabpaint']}, 23:47:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['tsvgx', 23:47:48 INFO - 'tsvgr_opacity', 23:47:48 INFO - 'tart', 23:47:48 INFO - 'tscrollx', 23:47:48 INFO - 'cart']}, 23:47:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:47:48 INFO - 'tsvgr_opacity', 23:47:48 INFO - 'tart', 23:47:48 INFO - 'tscrollx', 23:47:48 INFO - 'cart']}, 23:47:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:47:48 INFO - 'talos_options': ['--disable-e10s'], 23:47:48 INFO - 'tests': ['tp5o']}, 23:47:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:47:48 INFO - 'tests': ['tp5o']}, 23:47:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:47:48 INFO - 'talos_options': ['--disable-e10s', 23:47:48 INFO - '--xperf_path', 23:47:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:47:48 INFO - 'tests': ['tp5n']}, 23:47:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:47:48 INFO - 'talos_options': ['--xperf_path', 23:47:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:47:48 INFO - 'tests': ['tp5n']}}, 23:47:48 INFO - 'talos.zip': {'path': '', 23:47:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:47:48 INFO - Running post-action listener: _resource_record_post_action 23:47:48 INFO - ##### 23:47:48 INFO - ##### Finished populate-webroot step (success) 23:47:48 INFO - ##### 23:47:48 INFO - ##### 23:47:48 INFO - ##### Running create-virtualenv step. 23:47:48 INFO - ##### 23:47:48 INFO - Running pre-action listener: _resource_record_pre_action 23:47:48 INFO - Running main action method: create_virtualenv 23:47:48 INFO - Creating virtualenv /builds/slave/test/build/venv 23:47:48 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 23:47:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:47:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:47:48 INFO - Using real prefix '/tools/python27' 23:47:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:47:48 INFO - Installing distribute.............................................................................................................................................................................................done. 23:47:51 INFO - Installing pip.................done. 23:47:51 INFO - Return code: 0 23:47:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:51 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 0x10a9a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf8049fba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, '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.Friofd4ail/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.3kWM1gRTxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:47:51 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 23:47:51 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 23:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:51 INFO - 'HOME': '/Users/cltbld', 23:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:51 INFO - 'LOGNAME': 'cltbld', 23:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:51 INFO - 'MOZ_NO_REMOTE': '1', 23:47:51 INFO - 'NO_EM_RESTART': '1', 23:47:51 INFO - 'PAGER': '/bin/cat', 23:47:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:51 INFO - 'PWD': '/builds/slave/test', 23:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:51 INFO - 'SHELL': '/bin/bash', 23:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:47:51 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:51 INFO - 'USER': 'cltbld', 23:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:51 INFO - 'XPC_FLAGS': '0x0', 23:47:51 INFO - 'XPC_SERVICE_NAME': '0', 23:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:52 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 23:47:52 INFO - Cleaning up... 23:47:52 INFO - Return code: 0 23:47:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:47:52 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')]} 23:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:52 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')]} 23:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:52 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 0x10a9a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf8049fba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, '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.Friofd4ail/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.3kWM1gRTxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:47:52 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 23:47:52 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 23:47:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:47:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:52 INFO - 'HOME': '/Users/cltbld', 23:47:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:52 INFO - 'LOGNAME': 'cltbld', 23:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:52 INFO - 'MOZ_NO_REMOTE': '1', 23:47:52 INFO - 'NO_EM_RESTART': '1', 23:47:52 INFO - 'PAGER': '/bin/cat', 23:47:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:52 INFO - 'PWD': '/builds/slave/test', 23:47:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:52 INFO - 'SHELL': '/bin/bash', 23:47:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:47:52 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:47:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:52 INFO - 'USER': 'cltbld', 23:47:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:52 INFO - 'XPC_FLAGS': '0x0', 23:47:52 INFO - 'XPC_SERVICE_NAME': '0', 23:47:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:52 INFO - Downloading/unpacking psutil>=3.1.1 23:47:52 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 23:47:52 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 23:47:52 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 23:47:52 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 23:47:52 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 23:47:52 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 23:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:47:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:47:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:47:55 INFO - Installing collected packages: psutil 23:47:55 INFO - Running setup.py install for psutil 23:47:55 INFO - building 'psutil._psutil_osx' extension 23:47:55 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 23:47:56 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 23:47:56 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 23:47:56 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 23:47:56 INFO - building 'psutil._psutil_posix' extension 23:47:56 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 23:47:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:47:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:47:56 INFO - ^ 23:47:56 INFO - 1 warning generated. 23:47:56 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 23:47:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:47:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:47:56 INFO - Successfully installed psutil 23:47:56 INFO - Cleaning up... 23:47:56 INFO - Return code: 0 23:47:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:47:56 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')]} 23:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:56 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')]} 23:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:56 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf8049fba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, '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.Friofd4ail/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.3kWM1gRTxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:47:56 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.3'] in /builds/slave/test/build 23:47:56 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.3 23:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:56 INFO - 'HOME': '/Users/cltbld', 23:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:56 INFO - 'LOGNAME': 'cltbld', 23:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:56 INFO - 'MOZ_NO_REMOTE': '1', 23:47:56 INFO - 'NO_EM_RESTART': '1', 23:47:56 INFO - 'PAGER': '/bin/cat', 23:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:56 INFO - 'PWD': '/builds/slave/test', 23:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:56 INFO - 'SHELL': '/bin/bash', 23:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:47:56 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:56 INFO - 'USER': 'cltbld', 23:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:56 INFO - 'XPC_FLAGS': '0x0', 23:47:56 INFO - 'XPC_SERVICE_NAME': '0', 23:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:47:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:47:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:47:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:47:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:47:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:47:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:47:59 INFO - Installing collected packages: mozsystemmonitor 23:47:59 INFO - Running setup.py install for mozsystemmonitor 23:47:59 INFO - Successfully installed mozsystemmonitor 23:47:59 INFO - Cleaning up... 23:47:59 INFO - Return code: 0 23:47:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:47:59 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')]} 23:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:59 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')]} 23:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:59 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 0x10a9a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf8049fba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, '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.Friofd4ail/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.3kWM1gRTxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:47:59 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 23:47:59 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 23:47:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:47:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:59 INFO - 'HOME': '/Users/cltbld', 23:47:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:59 INFO - 'LOGNAME': 'cltbld', 23:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:59 INFO - 'MOZ_NO_REMOTE': '1', 23:47:59 INFO - 'NO_EM_RESTART': '1', 23:47:59 INFO - 'PAGER': '/bin/cat', 23:47:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:59 INFO - 'PWD': '/builds/slave/test', 23:47:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:59 INFO - 'SHELL': '/bin/bash', 23:47:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:47:59 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:47:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:59 INFO - 'USER': 'cltbld', 23:47:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:59 INFO - 'XPC_FLAGS': '0x0', 23:47:59 INFO - 'XPC_SERVICE_NAME': '0', 23:47:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:59 INFO - Downloading/unpacking blobuploader==1.2.4 23:47:59 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 23:47:59 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 23:47:59 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 23:47:59 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 23:47:59 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 23:47:59 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 23:48:02 INFO - Downloading blobuploader-1.2.4.tar.gz 23:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:48:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:48:02 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 23:48:02 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 23:48:02 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 23:48:02 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 23:48:02 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 23:48:02 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 23:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:48:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:48:03 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 23:48:03 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 23:48:03 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 23:48:03 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 23:48:03 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 23:48:03 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 23:48:03 INFO - Downloading docopt-0.6.1.tar.gz 23:48:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:48:03 INFO - Installing collected packages: blobuploader, requests, docopt 23:48:03 INFO - Running setup.py install for blobuploader 23:48:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:48:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:48:03 INFO - Running setup.py install for requests 23:48:04 INFO - Running setup.py install for docopt 23:48:04 INFO - Successfully installed blobuploader requests docopt 23:48:04 INFO - Cleaning up... 23:48:04 INFO - Return code: 0 23:48:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:48:04 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')]} 23:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:04 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')]} 23:48:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:04 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 0x10a9a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf8049fba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, '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.Friofd4ail/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.3kWM1gRTxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:48:04 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 23:48:04 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 23:48:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:48:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:04 INFO - 'HOME': '/Users/cltbld', 23:48:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:04 INFO - 'LOGNAME': 'cltbld', 23:48:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:04 INFO - 'MOZ_NO_REMOTE': '1', 23:48:04 INFO - 'NO_EM_RESTART': '1', 23:48:04 INFO - 'PAGER': '/bin/cat', 23:48:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:04 INFO - 'PWD': '/builds/slave/test', 23:48:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:04 INFO - 'SHELL': '/bin/bash', 23:48:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:48:04 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:48:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:04 INFO - 'USER': 'cltbld', 23:48:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:04 INFO - 'XPC_FLAGS': '0x0', 23:48:04 INFO - 'XPC_SERVICE_NAME': '0', 23:48:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:48:04 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-SWTGNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:48:04 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-8SMYfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-AgKstS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-JSWLMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-uoO3jE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-7SjRUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-WqhNyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-dBKd4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-cL2QA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-3Q8uex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-Y11Mc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:48:05 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-dbp3Kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:48:06 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-tcFhWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:48:06 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-XL43hO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:48:06 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-mLPPhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:48:06 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-qYPEhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:48:06 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-cebKtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:48:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:48:06 INFO - Running setup.py install for manifestparser 23:48:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:48:06 INFO - Running setup.py install for mozcrash 23:48:06 INFO - Running setup.py install for mozdebug 23:48:06 INFO - Running setup.py install for mozdevice 23:48:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:48:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:48:07 INFO - Running setup.py install for mozfile 23:48:07 INFO - Running setup.py install for mozhttpd 23:48:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:48:07 INFO - Running setup.py install for mozinfo 23:48:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:48:07 INFO - Running setup.py install for mozInstall 23:48:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:48:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:48:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:48:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:48:07 INFO - Running setup.py install for mozleak 23:48:07 INFO - Running setup.py install for mozlog 23:48:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:48:08 INFO - Running setup.py install for moznetwork 23:48:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:48:08 INFO - Running setup.py install for mozprocess 23:48:08 INFO - Running setup.py install for mozprofile 23:48:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:48:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:48:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:48:08 INFO - Running setup.py install for mozrunner 23:48:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:48:08 INFO - Running setup.py install for mozscreenshot 23:48:08 INFO - Running setup.py install for moztest 23:48:09 INFO - Running setup.py install for mozversion 23:48:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:48:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:48:09 INFO - Cleaning up... 23:48:09 INFO - Return code: 0 23:48:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:48:09 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')]} 23:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:09 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')]} 23:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:09 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 0x10a9a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf8049fba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, '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.Friofd4ail/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.3kWM1gRTxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:48:09 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 23:48:09 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 23:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:09 INFO - 'HOME': '/Users/cltbld', 23:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:09 INFO - 'LOGNAME': 'cltbld', 23:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:09 INFO - 'MOZ_NO_REMOTE': '1', 23:48:09 INFO - 'NO_EM_RESTART': '1', 23:48:09 INFO - 'PAGER': '/bin/cat', 23:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:09 INFO - 'PWD': '/builds/slave/test', 23:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:09 INFO - 'SHELL': '/bin/bash', 23:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:48:09 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:09 INFO - 'USER': 'cltbld', 23:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:09 INFO - 'XPC_FLAGS': '0x0', 23:48:09 INFO - 'XPC_SERVICE_NAME': '0', 23:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:48:09 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-fzx2QZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:48:09 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)) 23:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:48:09 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-OZ6PjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:48:09 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)) 23:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:48:09 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-HmRLzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:48:09 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)) 23:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:48:09 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-LjfyUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:48:09 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)) 23:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:48:09 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-10HHob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:48:09 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)) 23:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:48:09 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-F3Zijf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:48:10 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)) 23:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:48:10 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-Hw2vj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:48:10 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)) 23:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:48:10 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-pToln0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:48:10 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)) 23:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:48:10 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-Zg_XdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:48:10 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)) 23:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:48:10 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-O5VexT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:48:10 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)) 23:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:48:10 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-XzPthl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:48:10 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)) 23:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:48:10 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-T6zayH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:48:10 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)) 23:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:48:10 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-3O_kfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:48:10 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)) 23:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:48:10 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-qmHDQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:48:10 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)) 23:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:48:10 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-kG3xZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:48:11 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)) 23:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:48:11 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-DO5aEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:48:11 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)) 23:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:48:11 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-eJ3FIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:48:11 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)) 23:48:11 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)) 23:48:11 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)) 23:48:11 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)) 23:48:11 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)) 23:48:11 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)) 23:48:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:13 INFO - Downloading blessings-1.6.tar.gz 23:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:48:13 INFO - Installing collected packages: blessings 23:48:13 INFO - Running setup.py install for blessings 23:48:13 INFO - Successfully installed blessings 23:48:13 INFO - Cleaning up... 23:48:14 INFO - Return code: 0 23:48:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:48:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:48:14 INFO - Reading from file tmpfile_stdout 23:48:14 INFO - Current package versions: 23:48:14 INFO - blessings == 1.6 23:48:14 INFO - blobuploader == 1.2.4 23:48:14 INFO - docopt == 0.6.1 23:48:14 INFO - manifestparser == 1.1 23:48:14 INFO - mozInstall == 1.12 23:48:14 INFO - mozcrash == 0.17 23:48:14 INFO - mozdebug == 0.1 23:48:14 INFO - mozdevice == 0.48 23:48:14 INFO - mozfile == 1.2 23:48:14 INFO - mozhttpd == 0.7 23:48:14 INFO - mozinfo == 0.9 23:48:14 INFO - mozleak == 0.1 23:48:14 INFO - mozlog == 3.2 23:48:14 INFO - moznetwork == 0.27 23:48:14 INFO - mozprocess == 0.22 23:48:14 INFO - mozprofile == 0.28 23:48:14 INFO - mozrunner == 6.11 23:48:14 INFO - mozscreenshot == 0.1 23:48:14 INFO - mozsystemmonitor == 0.3 23:48:14 INFO - moztest == 0.7 23:48:14 INFO - mozversion == 1.4 23:48:14 INFO - psutil == 3.1.1 23:48:14 INFO - requests == 1.2.3 23:48:14 INFO - wsgiref == 0.1.2 23:48:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:14 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 0x10a9a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf8049fba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, '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.Friofd4ail/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.3kWM1gRTxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:48:14 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 23:48:14 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 23:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:14 INFO - 'HOME': '/Users/cltbld', 23:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:14 INFO - 'LOGNAME': 'cltbld', 23:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:14 INFO - 'MOZ_NO_REMOTE': '1', 23:48:14 INFO - 'NO_EM_RESTART': '1', 23:48:14 INFO - 'PAGER': '/bin/cat', 23:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:14 INFO - 'PWD': '/builds/slave/test', 23:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:14 INFO - 'SHELL': '/bin/bash', 23:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:48:14 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:14 INFO - 'USER': 'cltbld', 23:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:14 INFO - 'XPC_FLAGS': '0x0', 23:48:14 INFO - 'XPC_SERVICE_NAME': '0', 23:48:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:14 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)) 23:48:14 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)) 23:48:14 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)) 23:48:14 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)) 23:48:14 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)) 23:48:14 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)) 23:48:14 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)) 23:48:14 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)) 23:48:14 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)) 23:48:14 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)) 23:48:14 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)) 23:48:14 INFO - Cleaning up... 23:48:14 INFO - Return code: 0 23:48:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf8049fba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, '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.Friofd4ail/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.3kWM1gRTxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:48:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:14 INFO - 'HOME': '/Users/cltbld', 23:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:14 INFO - 'LOGNAME': 'cltbld', 23:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:14 INFO - 'MOZ_NO_REMOTE': '1', 23:48:14 INFO - 'NO_EM_RESTART': '1', 23:48:14 INFO - 'PAGER': '/bin/cat', 23:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:14 INFO - 'PWD': '/builds/slave/test', 23:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:14 INFO - 'SHELL': '/bin/bash', 23:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:48:14 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:14 INFO - 'USER': 'cltbld', 23:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:14 INFO - 'XPC_FLAGS': '0x0', 23:48:14 INFO - 'XPC_SERVICE_NAME': '0', 23:48:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:14 INFO - Downloading/unpacking jsonschema 23:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:48:17 INFO - Downloading/unpacking functools32 (from jsonschema) 23:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:17 INFO - Downloading functools32-3.2.3-2.tar.gz 23:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:48:17 INFO - warning: no files found matching '*.txt' 23:48:17 INFO - no previously-included directories found matching 'build' 23:48:17 INFO - no previously-included directories found matching 'dist' 23:48:17 INFO - no previously-included directories found matching '.git*' 23:48:17 INFO - Installing collected packages: jsonschema, functools32 23:48:17 INFO - Running setup.py install for functools32 23:48:17 INFO - warning: no files found matching '*.txt' 23:48:17 INFO - no previously-included directories found matching 'build' 23:48:17 INFO - no previously-included directories found matching 'dist' 23:48:17 INFO - no previously-included directories found matching '.git*' 23:48:17 INFO - Successfully installed jsonschema functools32 23:48:17 INFO - Cleaning up... 23:48:17 INFO - Return code: 0 23:48:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:48:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf8049fba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, '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.Friofd4ail/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.3kWM1gRTxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 23:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:17 INFO - 'HOME': '/Users/cltbld', 23:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:17 INFO - 'LOGNAME': 'cltbld', 23:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:17 INFO - 'MOZ_NO_REMOTE': '1', 23:48:17 INFO - 'NO_EM_RESTART': '1', 23:48:17 INFO - 'PAGER': '/bin/cat', 23:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:17 INFO - 'PWD': '/builds/slave/test', 23:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:17 INFO - 'SHELL': '/bin/bash', 23:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:48:17 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:17 INFO - 'USER': 'cltbld', 23:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:17 INFO - 'XPC_FLAGS': '0x0', 23:48:17 INFO - 'XPC_SERVICE_NAME': '0', 23:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:18 INFO - Downloading/unpacking flake8 23:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:48:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:48:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:48: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 23:48: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 23:48: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 23:48: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 23:48: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 23:48: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 23:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:48:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:48:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:48:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:48:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:48:21 INFO - no previously-included directories found matching 'docs/_build' 23:48:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:48:21 INFO - Running setup.py install for pep8 23:48:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:48:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:48:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:48:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:48:22 INFO - no previously-included directories found matching 'docs/_build' 23:48:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:48:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:48:22 INFO - Cleaning up... 23:48:22 INFO - Return code: 0 23:48:22 INFO - Running post-action listener: _resource_record_post_action 23:48:22 INFO - Running post-action listener: _start_resource_monitoring 23:48:22 INFO - Starting resource monitoring. 23:48:22 INFO - ##### 23:48:22 INFO - ##### Finished create-virtualenv step (success) 23:48:22 INFO - ##### 23:48:22 INFO - ##### 23:48:22 INFO - ##### Running install step. 23:48:22 INFO - ##### 23:48:22 INFO - Running pre-action listener: _resource_record_pre_action 23:48:22 INFO - Running main action method: install 23:48:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:48:22 INFO - Reading from file tmpfile_stdout 23:48:22 INFO - Detecting whether we're running mozinstall >=1.0... 23:48:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:48:22 INFO - Reading from file tmpfile_stdout 23:48:22 INFO - Output received: 23:48:22 INFO - Usage: mozinstall [options] installer 23:48:22 INFO - Options: 23:48:22 INFO - -h, --help show this help message and exit 23:48:22 INFO - -d DEST, --destination=DEST 23:48:22 INFO - Directory to install application into. [default: 23:48:22 INFO - "/builds/slave/test"] 23:48:22 INFO - --app=APP Application being installed. [default: firefox] 23:48:22 INFO - mkdir: /builds/slave/test/build/application 23:48:22 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'] 23:48:22 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 23:48:55 INFO - Reading from file tmpfile_stdout 23:48:55 INFO - Output received: 23:48:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:48:55 INFO - Running post-action listener: _resource_record_post_action 23:48:55 INFO - ##### 23:48:55 INFO - ##### Finished install step (success) 23:48:55 INFO - ##### 23:48:55 INFO - ##### 23:48:55 INFO - ##### Running run-tests step. 23:48:55 INFO - ##### 23:48:55 INFO - Running pre-action listener: _resource_record_pre_action 23:48:55 INFO - Running main action method: run_tests 23:48:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:48:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:48:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:48:55 INFO - 2016-05-18 23:48:55.990 screenresolution[1649:7838] starting screenresolution argv=screenresolution get 23:48:55 INFO - 2016-05-18 23:48:55.992 screenresolution[1649:7838] Display 0: 1600x1200x32@60 23:48:55 INFO - 2016-05-18 23:48:55.998 screenresolution[1650:7840] starting screenresolution argv=screenresolution list 23:48:56 INFO - Available Modes on Display 0 23:48:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:48:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:48:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:48:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:48:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:48:56 INFO - Intel Iris: 23:48:56 INFO - Chipset Model: Intel Iris 23:48:56 INFO - Type: GPU 23:48:56 INFO - Bus: Built-In 23:48:56 INFO - VRAM (Dynamic, Max): 1536 MB 23:48:56 INFO - Vendor: Intel (0x8086) 23:48:56 INFO - Device ID: 0x0a2e 23:48:56 INFO - Revision ID: 0x0009 23:48:56 INFO - Displays: 23:48:56 INFO - Display: 23:48:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:48:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:48:56 INFO - Display Serial Number: 1600x1200 60 23:48:56 INFO - Main Display: Yes 23:48:56 INFO - Mirror: Off 23:48:56 INFO - Online: Yes 23:48:56 INFO - Rotation: Supported 23:48:56 INFO - Return code: 0 23:48:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:48:56 INFO - Python 2.7.3 23:48:56 INFO - Return code: 0 23:48:56 INFO - grabbing minidump binary from tooltool 23:48:56 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')]} 23:48:56 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 0x10a848d30>, '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 0x10a66cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a83fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:48:56 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 23:48:56 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 23:48:56 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 23:48:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:48:56 INFO - Return code: 0 23:48:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:48:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:48:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:48:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:48:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:48:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:48:57 INFO - Return code: 0 23:48:57 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/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 23:48:57 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/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0313 --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 23:48:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Friofd4ail/Render', 23:48:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:57 INFO - 'HOME': '/Users/cltbld', 23:48:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:57 INFO - 'LOGNAME': 'cltbld', 23:48:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:48:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:57 INFO - 'MOZ_NO_REMOTE': '1', 23:48:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:48:57 INFO - 'NO_EM_RESTART': '1', 23:48:57 INFO - 'PAGER': '/bin/cat', 23:48:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:57 INFO - 'PWD': '/builds/slave/test', 23:48:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:48:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:57 INFO - 'SHELL': '/bin/bash', 23:48:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3kWM1gRTxD/Listeners', 23:48:57 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 23:48:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:57 INFO - 'USER': 'cltbld', 23:48:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:57 INFO - 'XPC_FLAGS': '0x0', 23:48:57 INFO - 'XPC_SERVICE_NAME': '0', 23:48:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:57 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/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--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 23:48:57 INFO - mozversion application_buildid: 20160518214151 23:48:57 INFO - mozversion application_changeset: aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 23:48:57 INFO - mozversion application_display_name: Nightly 23:48:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:48:57 INFO - mozversion application_name: Firefox 23:48:57 INFO - mozversion application_remotingname: firefox 23:48:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:48:57 INFO - mozversion application_vendor: Mozilla 23:48:57 INFO - mozversion application_version: 49.0a1 23:48:57 INFO - mozversion platform_buildid: 20160518214151 23:48:57 INFO - mozversion platform_changeset: aa7ea6cc079202092ea3234ad9bd8fd72db3e3f2 23:48:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:48:57 INFO - mozversion platform_version: 49.0a1 23:48:57 INFO - using testdate: 1463640537 23:48:57 INFO - actual date: 1463640537 23:48:57 INFO - starting webserver on 'localhost:49279' 23:48:57 INFO - SUITE-START | Running 2 tests 23:48:57 INFO - TEST-START | dromaeo_css 23:48:57 INFO - operating with platform_type : mac_ 23:48:57 INFO - Initialising browser for dromaeo_css test... 23:48:57 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpjwhwjn/profile http://localhost:49279/getInfo.html) 23:49:00 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 23:49:00 INFO - PROCESS | 1669 | colorDepth:24 23:49:00 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 23:49:00 INFO - PROCESS | 1669 | __metrics 23:49:00 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:49:00 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:49:00 INFO - TEST-INFO | 1669: exit 0 23:49:00 INFO - Browser initialized. 23:49:00 INFO - Running cycle 1/1 for dromaeo_css test... 23:49:00 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpjwhwjn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:49:12 INFO - PROCESS | 1671 | RSS: Main: 238952448 23:49:12 INFO - PROCESS | 1671 | 23:50:04 INFO - PROCESS | 1671 | 1463640604462 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] 23:52:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 23:52:16 INFO - PROCESS | 1671 | RSS: Main: 374284288 23:52:16 INFO - PROCESS | 1671 | 23:55:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 23:55:20 INFO - PROCESS | 1671 | RSS: Main: 376856576 23:55:20 INFO - PROCESS | 1671 | 23:58:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 23:58:22 INFO - PROCESS | 1671 | RSS: Main: 366936064 23:58:22 INFO - PROCESS | 1671 | 00:01:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 00:01:25 INFO - PROCESS | 1671 | RSS: Main: 367931392 00:01:25 INFO - PROCESS | 1671 | 00:04:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 00:04:29 INFO - PROCESS | 1671 | RSS: Main: 335925248 00:04:29 INFO - PROCESS | 1671 | 00:07:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 00:07:32 INFO - PROCESS | 1671 | RSS: Main: 375885824 00:07:32 INFO - PROCESS | 1671 | 00:07:32 INFO - PROCESS | 1671 | __start_tp_report 00:07:32 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 00:07:32 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 00:07:32 INFO - PROCESS | 1671 | |i|pagename|runs| 00:07:32 INFO - PROCESS | 1671 | |0;dojo.html;3465.9826700866493;3475.8262086895634;3502.4746288056795;3507.736919731021;3552.0054384772275;5674.744636491358;5688.260526131602;5691.430856914307;5759.078547432412;5789.623674461162;5527.281453411061;5757.280339957505;5776.480116789488;5887.85280367991;5913.438223368784;5124.052050370675;5157.6647517911215;5238.161894096946;5240.502152295526;5344.946550534483;5313.123334649778;5360.142377219646;5384.057789886774;5388.622796404254;5410.945890541103;5183.663061900965;5221.216817477371;5231.372235331746;5250.9474905250845;5275.340582427197;5187.714675692835;5190.221466779975;5246.265522826808;5263.473652634744;5320.095583750772;5175.767090480937;5177.896442071137;5212.713300768456;5221.791128354861;5242.81650142247;5687.431256874321;5764.394738552433;5771.711414429262;5788.04497257952;5792.420757924216;5160.200168973815;6143.419763699212;6194.4425001749605;6209.472194863442;6234.501239900797;5592.909382670381;6105.847353816146;6249.875002499924;6336.239651241881;6372.235331760172;5881.529477641821;5930.436608522184;5971.940280597182;5980.910286345708;5982.431668991482;6243.687815609293;6280.905786413207;6292.119103325538;6306.779262725782;6320.178376810985;6232.626042437469;6238.157848690459;6249.312575616678;6252.812415627538;6266.216722909637;6265.530085243625;6327.17746692927;6362.236531616236;6375.808725738235;6388.744450222032;6112.169143789106;6202.255729312511;6279.560430769893;6299.842503937438;6307.4954003679595;6277.843053923689;6324.557280990379;6335.398137177053;6346.365363463709;6346.587471814318;4892.021595680878;4973.35346404965;4981.078500477351;5004.249362595567;5054.317667114966;4508.549145085531;4533.909321813545;4535.659825513099;4550.13547425988;4587.610053145453;5720.141978703145;5755;5759.568032397503;5763.740631671585;5774.191613174159;6232.594881332699;6245.843853903689;6300.621962682254;6317.399847014526;6330.493560515148;5762.683052432073;5764.250647415809;5770.13447982806;5785.363610002896;5800;5442.346918369821;5466.835994920159;5470.398256191816;5471.562275017989;5475.06923822951;6286.18279623646;6304.873902521924;6319.8104056878365;6351.3331100235655;6392.264889537762;6356.586821856565;6365.172527571386;6365.427111559982;6379.457746091449;6405.423511883952;6392;6403.007533832263;6406;6413.134226879311;6419.614823110628;5090.770915308746;6143.815685529441;6176.042713379254;6232.376762323732;6317.873642527215;5226.398964119175;6255.522882832422;6318.462930650762;6324.3991820776955;6334.303226644974;6048.818535443944;6211.472024877936;6213.9067913980425;6229.906551401823;6236.688165591793;5146.8934179151665;6031.60794548353;6101.277634935821;6124.625058545733;6195.752169913333;5995.640261584319;6055.485283750754;6066.332703402534;6066.636001839904;6094.99496405025;5997.0404735242155;6081.787137450168;6222.688865556614;6225.719842607003;6244.906326405016;5979.611325263844;6081.300650425081;6099.542534309856;6105.511559075175;6128.233970753656 00:07:32 INFO - PROCESS | 1671 | |1;ext.html;524.6301357542933;526.673462453279;527.9260903473512;560.585166976437;572.9743758671976;25113.62329565058;43044;44471.555284447146;44598.33102503461;46970.295445568256;45437;45632;45885;45951;45986.77006614962;8694.478331300143;9713.320067595254;9921.900780992206;9927.056929591714;9932;8816.779580510474;11786.233894796853;12051.975582075534;12071.637850864488;12300.478401927583;11986.820197697041;12055.578054768084;12069.275843449377;12069.698257543543;12088.456019479101;8753.912460875374;11794.469248883821;11958.103142264321;12068.57759978401;12088.972437342836;11919.761604767857;12018.87981120195;12043.518259269618;12046;12057.758844823054;41996.32014719408;42346.57653423457;42422;42453.36319955203;42524.78737606323;3219.8551065202123;3258.4786434170655;3308.106811160973;3319.0540695901796;3497.8251087445524;25831.870840645675;29270.560941585893;30325.241868953453;31464.685353146633;31475.898343558096;2733.6992930777596;2832.475991941477;2867.985660071686;2869.239651492356;2889.2343528964843;491446.85531446926;493991;494520;494883;495574;62046;62147.6892615534;62258;62316;63118.36881631217;65840.67079664666;65850;65882.34117658858;65893.67053164703;65979;17115.22981465837;17320.91339543294;17321.826781732023;17349.306027759;17356.82643173552;12060.457279422271;12300.938495307466;12979;13219.405126769318;13256.469741210436;12972.935135324451;15481.690366192839;16868.493945181664;16896.32414703398;16974.32102715902;4934.1118598651865;6086.817395478142;6326.620402775849;6332.810015699536;6513.348665133449;927.3276874266238;989.2926557511414;1010.5604062232806;1057.3444464432102;1177.370106992772;30248;30576.8434908778;30578.082657519866;30588;30704;964.4840010594298;1129.7966366054188;1137.8122609769296;1197.6167626359481;1208.945597448117;15258;15408.460703875311;15632.609184770472;15806.209689515708;15999.280032398337;699532;710959;711966;711984;712574;50902.49097508978;64925;64985;65080.67459662671;65342.019869701995;1294.6504443800309;1378.06980288307;1434.6485111147836;1522.4519173097676;1554.2461905975592;29932.97919872926;30742.385152297276;31223;31511.84244078765;32168.678313217504;55395.723021384634;55442;56507.86984260374;56560.43439565716;56588.86822263614;593732;597640;599386;600212;600214;36081.639183607826;36931;42608.78695606502;44295;45700;47636.761816190694;48703.756481217366;48960.510394895595;49114.52656420313;49159.016819664124;37010.25979480449;39894.60105399025;40277.597224027384;41565.96085097897;42742.358864616435;38452.230955380175;38850.61149388583;39389;40374;40400.39399408952;38834.80582597069;39222.411663824496;39997.200055999296;40087.79956100201;40206.798966004986;31054;31607.683923161392;34773;34788.82605586956;34814.65185348114;24568;25186.62220066664;25482.490350192522;25680.357991050372;25722.35694107662 00:07:32 INFO - PROCESS | 1671 | |2;jquery.html;392.9860958726486;397.9522457305124;401.1835913915183;404.141199950106;425.3768229543718;19295.710564341527;19744.802551974477;20549.075291611894;20721.274755383565;20793.480162995933;20064.097115629796;20171.798282017175;21357;21608.243711470102;21629.242976495825;5626.690532020737;5931.911021334683;5935.703214839241;5936.6734829584575;5944.762209511636;4955.207166853304;5660.886782264352;5761.9423805762035;5764.51001664859;5893.58744887858;5763.9711801440935;5795.449432303924;5805.274340707411;5805.709714514257;5806.44838740319;5545.390007099216;5788.942110578924;5789;5789.536837053047;5798.942010579883;5044.319016932704;5066.214736715815;5071.137906555894;5078.5937125029905;5079.8222062228015;24547.754522454903;25852.482950341077;26140.73859261402;26926;27017.054403095797;2193.6161171795034;2201.636729939558;2234.72065991751;2363.8581685098948;2372.7745864143053;15107.697846043018;15216.71811162057;15274.167099947967;15314.310856011507;15447.768283475756;1581.5571639940836;1757.5254681236122;1825.4341154242227;1908.895010774407;1928.6528424883516;114980;118912;119467.40266298699;120038.79961200451;122381.38809305985;9621;11738.94130529342;11741.354225517507;11794.76410471803;11815.577806549636;15188.01277916932;15262.771058434133;15271.38914443432;15283.770743438858;15286.38854445832;8580.613872375758;8790.516521591246;8800.295976321879;8880;8898.199162075443;8689.782755431164;8703.173198546128;8714.259287960595;8815.779605509786;8842.646294148162;4028.1943611277275;4606.815727370935;4683.578477936934;4703.741294228782;4788;1713.7943446786464;1886.820752028555;1986.5430950881378;1991.32295019694;2011.577568710558;640.0846789091551;734.6179986407038;819.5205804604374;844.7127976488024;1103.6523495099018;19460.18758730798;20352.89823550873;20617.58764824695;20712.378628640865;20764.792352076285;963.2631037256365;983.331334692416;983.6065573770549;992.5186284651938;1052.8683914510686;10751.0324070834;10781.665806092844;10916.01755841978;10930.344179349106;10997.395143267036;487040;634818;638382;647362;666066;92582;131026.37947241002;133234;135250;143634;971.4608392342318;996.9252306077074;1023.4268809466722;1023.4575674892319;1047.7171163785736;14130.78803817923;15934.60163495922;16734;18267.725984110515;18996.335128270443;54140.72929635327;55100.72449637726;55516.72241638766;55924.48339824799;56740.01409950632;75190.87213691689;75367.62316188384;76397.85403218845;77606;78296.21703783117;7135.571865688075;7576.28025337592;8047.51714897108;8108.067572229033;8563.828723425371;11853.585124520945;12942.935285323512;13148.671283217996;13313.933430332787;13503.189808611514;11062.17033722458;11147.163962702993;11333.469981552385;11344.546218151181;11390.658280251275;8892.911070889208;9244.537773111182;9395.483248421402;10351.327163734333;11264.09887209005;8692.565371731514;10539.473026348498;10959.452027398758;11809.055275578105;11853.644390668294;8202.138775428493;8253.422260441712;8273.296769774637;8491.490510569385;8522.678984757373;10425.687229383131;11050.834237486604;11394.94302528482;11545.36500492481;11598.5360585579 00:07:32 INFO - PROCESS | 1671 | |3;mootools.html;1435.5262763288115;1508.2458770614692;1595.4415954415956;1847.2610955617752;1995.720599116124;8045.275925166734;8815.735527934165;9100.180983711465;9117.954410227949;9229.307801914849;8883.378163528556;8947.284217262619;9038.63845446183;9040.050794666564;9040.8643870342;2411.7829395354415;2447.4615584571457;2449.6692946452267;2456.520978409211;2477.2939712182033;2386.3484207550805;2447.5472037673;2449.632555116729;2450.1179575352858;2455.0548039004934;2414.6015907375263;2425.7331693513697;2440.2801173653816;2450.7304196538457;2454.9386265343333;2405.2663937499055;2411.288669842401;2411.7347091819975;2418.6613874057557;2437.24445421918;2386.4033991502124;2417.057347634424;2420.8547487150827;2421.539907417585;2422.733499315074;8749.03760586335;8929.196372326456;9022.368434209608;9024.368294219406;9026.54867256641;5444.91832622511;5629.352624448159;5637.577181711347;5651.632643878134;5654.9717251413895;2873.928151796201;3610.4042832932537;3658.268346330718;3665;3697.6487233712755;935.1630290889684;983.7835676151235;1024.5901639344247;1028.6348346337018;1031.056583226347;10821.621243256448;12477.93761031189;12504.187227830253;12512.937435312766;12517.874821251762;3916.412538119248;4681.204195286809;4829.251466022772;4846.103470857903;4850.417949845971;9529.52352382392;9548.283878708986;9549.331546791658;9549.856752148724;9556;5467.617266791366;5469.097598896137;5469.72651367438;5471.097268950579;5480.424555421703;4560.863174104782;4693.20215563355;4916.311716359713;5036.874078148002;5069.340985671839;3916.5300163980505;3942.1721438498234;4006.779627120536;4034.6570541503716;4065.3901914712437;1081.9567217311376;1212.6968257935516;1220.5170717578185;1233.6114124050896;1247.4760600547793;623.6320570863122;681.4990981628499;927.3415874729018;975.4342481360935;985.2905907746555;5297.947020529746;5919.289685237799;5951.434613711691;6119.602225855305;6181.196444462281;503.50153348185034;505.8887044850127;520.7968892132071;521.4342438454244;648.3725848121155;3946.980265098656;4087.529934057562;4123.525794533667;4159.396887451303;4198.782353117562;10842.674719758419;11224.494897729623;11313.264637798517;11322.207445478902;11332.036776874058;11143.665690029313;11271.309303604525;11319.603813866475;11337.319760814378;11341.886581134248;8109.472884262504;8455.830883382421;8513.787155321166;8565.614547345385;8596.699115530926;5174.197999310113;5905.822825315247;6208.596441231305;6328.145700330397;6396.712147953446;8227.259546640831;9934.503274836374;10385.740356491147;10530.157587392838;10531.94734026325;10383.2212584055;10489.423081730578;10521.736956576147;10643.308184967953;10696.839547406951;7627.5042122261875;7739.961300193463;7796.220377962159;7801.4929029612895;7830.33442157423;8384.58077096155;8434.03008653988;8436.915630843612;8511.40420170607;8537.701180458655;7612.619369031416;7638.427117966286;7719.807004824925;7874.055113386201;7899.763007109796;7255.963720181366;7338.70645174187;7400.666969986256;7415.888761668687;7512.511686740344;6923.723051078102;7170.318819712119;7232.493725439169;7236.312550307713;7295.890561641473;5053.393592768891;5084.415292241291;5105.106606343949;5158.252053452154;5302.965921645242;5061.797528098982;5070.822521211888;5073.2897394365555;5144.151215049625;5436.755346009518 00:07:32 INFO - PROCESS | 1671 | |4;prototype.html;303.60379704485644;315.91944054266156;320.1132861972336;323.14044641254253;326.19918101061893;5051.116054690433;5085.593152547796;5152.201408781641;5216.930529241506;5314.953742809017;4407.11857628474;5029.270755740425;5174.223866420039;5261.0793111205485;5349.491798279158;3407.761456698032;3812.332841752696;3924.4505769192338;3964.810556832953;4039.798010099498;3238.740900727936;3398.09950363154;3944.901377465572;3974.9006274843214;3976.906350753537;3017.838132319062;3539.4159963605975;4022.919541609166;4026.0337518995525;4046.9967366153796;3586.7668601541077;3742.6070262622306;3853.460515527828;3976.9801150994203;4078.857239996586;3133.85897634607;3268.7385009199206;3516.43737002081;3536.6993805526563;3588.9102772430897;4234.513031001444;5102.923456148161;5140.278006867704;5143.665661732013;5170.224466330081;3453.2575496268423;3862.682390565666;3912.080661044652;4504.6396288296855;4728.196206644879;4977.004599080198;5581.274434323512;5630.971845140748;5653.773849046074;5665.433456654343;3949.5260568731937;3980.681545476355;3983.322835118008;3984.2230765000836;3997.0821300450834;4841.273808928654;5373.9731301343245;5707.229524014205;5797.507211886995;5848.853778655525;3234.9971508832336;3410.5225268462436;3785.413260944558;3997.860609726214;4006.5392479864604;3522.418800897823;3608.7654302470255;3660.4875317455576;4033.8184781684895;4070.2266569351727;3736.3834967230646;4200.6429453496185;4638.675292729544;4682.555157260055;4698.1778188816825;4883.804647814119;4926.950730492649;5599.89205990671;5678;5696.316442026983;1200.3578085724096;1292.043887523226;1372.2246930484243;1396.986030139706;1411.9929400352933;812.6871154605519;853.8292341531718;869.8173383589517;871.6687658689657;901.2159421303377;3962.7424217425773;4345.045369149009;5041.420236672756;5128.794733237686;5206.891209060261;4367.170237654836;4862.832920099221;4865.9756701216975;5002.974985125051;5382.896506216294;4864.951350486521;5157.561607263349;5170.017696637627;5208.817691380784;5240.6374342670415;2680.1691475642615;2681.4771119631873;3006.053093275611;3020.3539071206246;3020.909372718778;4646.558576935186;5655.72746132117;5689.743961521742;6015.218021657157;6036.849078772978;5304.912492938938;5362.678239305654;5433.712603874678;5467.644603100785;5832.475077243069;4967.7019378837385;5752.597318187771;5955.880882382328;5978;5984.9401505986125;5326.201069839555;5727.541796656341;5737.655740655574;5753.683547404766;5796.594238403271;5080.288759573588;5256.793356052728;5479.890402192013;5603.887922241451;5685.971570142123;5512.834614961397;5902.911456328073;5972.283326000907;5997.640141591519;6034.39656034393;3401.5237866699176;3432.262063656326;3785.299719551892;3961.504811898513;3975.8807235782974;5444.754986025718;5847.94152058491;5854.4730974212525;5879.059910229699;5890.4993075589055;4740.5733483986605;5034.244863270539;5413.864653383697;5639.379668236573;5706.457886500776;5260.73696315175;5384.784608615611;5677.687727175045;5705.971470142623;5718.313802343745;5514.36584792754;5579.944200557942;5585.441455854382;5587.832365029056;5620.831375058755;5341.359036915595;5526.889462210653;5580.804671836466;5729.971350143389;5752.597318187687;4541.386912766734;5349.705766182898;5609.242752228397;5646.85882852932;5657.717114144194 00:07:32 INFO - PROCESS | 1671 | |5;yui.html;653.7221680785666;718.5616773768;740.3669862267758;740.8666440040794;744.8919906613542;14391.712165756704;14518.927405362985;15101.24493775311;15393.923030384847;15628.84371156288;14546.563603091898;14661.266936653152;14709.043912145704;14732.337044833008;14746.778798318008;724.481995373308;752.6048824367197;752.7478294771258;930.265090578444;947.0955237747969;749.0000848866742;750.5159172333841;753.2467532467532;906.9501177435238;941.9717408477757;989.2877128467492;992.270681049428;996.217968894418;999.0958182844537;1002.6791426743445;986.2455221755363;987.8913319534845;987.9604815807359;990.479998001048;994.5773046455228;566.9546436285087;713.1406654980735;734.2033893225856;735.1766022055278;736.3704033051717;14799.556013319618;15136.848631513654;15169.848301516953;15178.772318415233;15187.696246075127;9997.250206234561;12109.515619375214;12201.511939522408;12371.15458457796;12728.23630582168;10696.144308455305;10716.78566428667;10876;11294.548218071268;11602.825957610643;4026.1545075534173;4064.878053658395;4189.120284740208;4210.51579068408;4213.47331583552;79679;80487.19512804855;80600.59699701464;81736.59131704363;82418;9455.52722363879;9479.952600237093;9667.903320966841;9711.708648740549;9754.268429867647;21827.345179644635;21988.780112198994;21991.780082199293;22019.449513761963;22339.10643574239;7305.853882922418;7341.706331746777;7375.852482950419;7532.472726909064;7684.423668224906;3809.5047643806683;3915.275674000319;3917.8628747994253;3940.4286378474962;3992.121733218687;3402.2685122698244;3502.0194345583277;3506.50908872758;3507.2108775525303;3507.5790905091553;637.5664548107331;641.7272659119856;646.4698946863621;648.144449326884;658.4567731621336;487.6196566677997;513.9254808052812;514.4406577278012;544.3658138268952;562.1325282059405;7472.439567032386;7528.51064680794;7595;7659.348955338859;8010.679572817138;511.10556526079364;553.441024565192;556.1490918894099;577.2351634084854;582.2256398989334;3452.3440546296124;3587.461880717913;3633.8364773585254;3806.333891568964;3834.367329390675;87944;91576.6263506048;92956;93157.06842931619;93688.59467107999;77973.61013195012;77988;78207;81046.18953810386;81509.59245203737;72227.55544889178;72317.63841180761;72685.90971135332;72917;72985.90521142186;7117.181524124792;7247.677154364439;7272.454565907472;7368.410527157922;7401.851962960818;45068.42260520866;45146;45354.77322613366;45433.54566454293;45976.77011614921;61836.690816545626;62348.37651623607;62898.05652915118;63052;63116.68441657762;9977;10023.949880250551;10027.398356098658;10227.130693890806;10339.379637221791;10273.178145748472;10302;10595.894041059491;10647;10967.242197846195;9370.34407591462;9478.289128315542;9680.370775899544;9944.254180936603;10022.448765317977;8485.40602157843;8730.453194026042;9342.579583918876;9363.906360936304;9893.55479003461;9045.864312035457;9397.389169703947;9444.386114902509;9639.759006024906;9808.313418060667;8277.420580559303;8450.661973521237;8519.062903080532;8731.301495880187;8754.124587541195;6796.592204467748;7939.2060793920145;7973.162817904268;8193.754187374388;8421.284190843848 00:07:32 INFO - PROCESS | 1671 | __end_tp_report 00:07:32 INFO - PROCESS | 1671 | __start_cc_report 00:07:32 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1329 00:07:32 INFO - PROCESS | 1671 | __end_cc_report 00:07:32 INFO - PROCESS | 1671 | __startTimestamp1463641652944__endTimestamp 00:07:32 INFO - PROCESS | 1671 | 00:07:32 INFO - PROCESS | 1671 | ------- Summary: start ------- 00:07:32 INFO - PROCESS | 1671 | Number of tests: 6 00:07:32 INFO - PROCESS | 1671 | 00:07:32 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5786.14 Median:5997.04 stddev:633.77 (10.6%) stddev-sans-first:609.02 00:07:32 INFO - PROCESS | 1671 | Values: 3466.0 3475.8 3502.5 3507.7 3552.0 5674.7 5688.3 5691.4 5759.1 5789.6 5527.3 5757.3 5776.5 5887.9 5913.4 5124.1 5157.7 5238.2 5240.5 5344.9 5313.1 5360.1 5384.1 5388.6 5410.9 5183.7 5221.2 5231.4 5250.9 5275.3 5187.7 5190.2 5246.3 5263.5 5320.1 5175.8 5177.9 5212.7 5221.8 5242.8 5687.4 5764.4 5771.7 5788.0 5792.4 5160.2 6143.4 6194.4 6209.5 6234.5 5592.9 6105.8 6249.9 6336.2 6372.2 5881.5 5930.4 5971.9 5980.9 5982.4 6243.7 6280.9 6292.1 6306.8 6320.2 6232.6 6238.2 6249.3 6252.8 6266.2 6265.5 6327.2 6362.2 6375.8 6388.7 6112.2 6202.3 6279.6 6299.8 6307.5 6277.8 6324.6 6335.4 6346.4 6346.6 4892.0 4973.4 4981.1 5004.2 5054.3 4508.5 4533.9 4535.7 4550.1 4587.6 5720.1 5755.0 5759.6 5763.7 5774.2 6232.6 6245.8 6300.6 6317.4 6330.5 5762.7 5764.3 5770.1 5785.4 5800.0 5442.3 5466.8 5470.4 5471.6 5475.1 6286.2 6304.9 6319.8 6351.3 6392.3 6356.6 6365.2 6365.4 6379.5 6405.4 6392.0 6403.0 6406.0 6413.1 6419.6 5090.8 6143.8 6176.0 6232.4 6317.9 5226.4 6255.5 6318.5 6324.4 6334.3 6048.8 6211.5 6213.9 6229.9 6236.7 5146.9 6031.6 6101.3 6124.6 6195.8 5995.6 6055.5 6066.3 6066.6 6095.0 5997.0 6081.8 6222.7 6225.7 6244.9 5979.6 6081.3 6099.5 6105.5 6128.2 00:07:32 INFO - PROCESS | 1671 | 00:07:32 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:74193.48 Median:38842.71 stddev:162305.58 (417.9%) stddev-sans-first:162666.64 00:07:32 INFO - PROCESS | 1671 | Values: 524.6 526.7 527.9 560.6 573.0 25113.6 43044.0 44471.6 44598.3 46970.3 45437.0 45632.0 45885.0 45951.0 45986.8 8694.5 9713.3 9921.9 9927.1 9932.0 8816.8 11786.2 12052.0 12071.6 12300.5 11986.8 12055.6 12069.3 12069.7 12088.5 8753.9 11794.5 11958.1 12068.6 12089.0 11919.8 12018.9 12043.5 12046.0 12057.8 41996.3 42346.6 42422.0 42453.4 42524.8 3219.9 3258.5 3308.1 3319.1 3497.8 25831.9 29270.6 30325.2 31464.7 31475.9 2733.7 2832.5 2868.0 2869.2 2889.2 491446.9 493991.0 494520.0 494883.0 495574.0 62046.0 62147.7 62258.0 62316.0 63118.4 65840.7 65850.0 65882.3 65893.7 65979.0 17115.2 17320.9 17321.8 17349.3 17356.8 12060.5 12300.9 12979.0 13219.4 13256.5 12972.9 15481.7 16868.5 16896.3 16974.3 4934.1 6086.8 6326.6 6332.8 6513.3 927.3 989.3 1010.6 1057.3 1177.4 30248.0 30576.8 30578.1 30588.0 30704.0 964.5 1129.8 1137.8 1197.6 1208.9 15258.0 15408.5 15632.6 15806.2 15999.3 699532.0 710959.0 711966.0 711984.0 712574.0 50902.5 64925.0 64985.0 65080.7 65342.0 1294.7 1378.1 1434.6 1522.5 1554.2 29933.0 30742.4 31223.0 31511.8 32168.7 55395.7 55442.0 56507.9 56560.4 56588.9 593732.0 597640.0 599386.0 600212.0 600214.0 36081.6 36931.0 42608.8 44295.0 45700.0 47636.8 48703.8 48960.5 49114.5 49159.0 37010.3 39894.6 40277.6 41566.0 42742.4 38452.2 38850.6 39389.0 40374.0 40400.4 38834.8 39222.4 39997.2 40087.8 40206.8 31054.0 31607.7 34773.0 34788.8 34814.7 24568.0 25186.6 25482.5 25680.4 25722.4 00:07:32 INFO - PROCESS | 1671 | 00:07:32 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:35610.81 Median:25996.61 stddev:103044.53 (396.4%) stddev-sans-first:103299.66 00:07:32 INFO - PROCESS | 1671 | Values: 393.0 398.0 401.2 404.1 425.4 19295.7 19744.8 20549.1 20721.3 20793.5 20064.1 20171.8 21357.0 21608.2 21629.2 5626.7 5931.9 5935.7 5936.7 5944.8 4955.2 5660.9 5761.9 5764.5 5893.6 5764.0 5795.4 5805.3 5805.7 5806.4 5545.4 5788.9 5789.0 5789.5 5798.9 5044.3 5066.2 5071.1 5078.6 5079.8 24547.8 25852.5 26140.7 26926.0 27017.1 2193.6 2201.6 2234.7 2363.9 2372.8 15107.7 15216.7 15274.2 15314.3 15447.8 1581.6 1757.5 1825.4 1908.9 1928.7 114980.0 118912.0 119467.4 120038.8 122381.4 9621.0 11738.9 11741.4 11794.8 11815.6 15188.0 15262.8 15271.4 15283.8 15286.4 8580.6 8790.5 8800.3 8880.0 8898.2 8689.8 8703.2 8714.3 8815.8 8842.6 4028.2 4606.8 4683.6 4703.7 4788.0 1713.8 1886.8 1986.5 1991.3 2011.6 640.1 734.6 819.5 844.7 1103.7 19460.2 20352.9 20617.6 20712.4 20764.8 963.3 983.3 983.6 992.5 1052.9 10751.0 10781.7 10916.0 10930.3 10997.4 487040.0 634818.0 638382.0 647362.0 666066.0 92582.0 131026.4 133234.0 135250.0 143634.0 971.5 996.9 1023.4 1023.5 1047.7 14130.8 15934.6 16734.0 18267.7 18996.3 54140.7 55100.7 55516.7 55924.5 56740.0 75190.9 75367.6 76397.9 77606.0 78296.2 7135.6 7576.3 8047.5 8108.1 8563.8 11853.6 12942.9 13148.7 13313.9 13503.2 11062.2 11147.2 11333.5 11344.5 11390.7 8892.9 9244.5 9395.5 10351.3 11264.1 8692.6 10539.5 10959.5 11809.1 11853.6 8202.1 8253.4 8273.3 8491.5 8522.7 10425.7 11050.8 11394.9 11545.4 11598.5 00:07:32 INFO - PROCESS | 1671 | 00:07:32 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5839.01 Median:5072.06 stddev:3326.58 (65.6%) stddev-sans-first:3319.45 00:07:32 INFO - PROCESS | 1671 | Values: 1435.5 1508.2 1595.4 1847.3 1995.7 8045.3 8815.7 9100.2 9118.0 9229.3 8883.4 8947.3 9038.6 9040.1 9040.9 2411.8 2447.5 2449.7 2456.5 2477.3 2386.3 2447.5 2449.6 2450.1 2455.1 2414.6 2425.7 2440.3 2450.7 2454.9 2405.3 2411.3 2411.7 2418.7 2437.2 2386.4 2417.1 2420.9 2421.5 2422.7 8749.0 8929.2 9022.4 9024.4 9026.5 5444.9 5629.4 5637.6 5651.6 5655.0 2873.9 3610.4 3658.3 3665.0 3697.6 935.2 983.8 1024.6 1028.6 1031.1 10821.6 12477.9 12504.2 12512.9 12517.9 3916.4 4681.2 4829.3 4846.1 4850.4 9529.5 9548.3 9549.3 9549.9 9556.0 5467.6 5469.1 5469.7 5471.1 5480.4 4560.9 4693.2 4916.3 5036.9 5069.3 3916.5 3942.2 4006.8 4034.7 4065.4 1082.0 1212.7 1220.5 1233.6 1247.5 623.6 681.5 927.3 975.4 985.3 5297.9 5919.3 5951.4 6119.6 6181.2 503.5 505.9 520.8 521.4 648.4 3947.0 4087.5 4123.5 4159.4 4198.8 10842.7 11224.5 11313.3 11322.2 11332.0 11143.7 11271.3 11319.6 11337.3 11341.9 8109.5 8455.8 8513.8 8565.6 8596.7 5174.2 5905.8 6208.6 6328.1 6396.7 8227.3 9934.5 10385.7 10530.2 10531.9 10383.2 10489.4 10521.7 10643.3 10696.8 7627.5 7740.0 7796.2 7801.5 7830.3 8384.6 8434.0 8436.9 8511.4 8537.7 7612.6 7638.4 7719.8 7874.1 7899.8 7256.0 7338.7 7400.7 7415.9 7512.5 6923.7 7170.3 7232.5 7236.3 7295.9 5053.4 5084.4 5105.1 5158.3 5303.0 5061.8 5070.8 5073.3 5144.2 5436.8 00:07:32 INFO - PROCESS | 1671 | 00:07:32 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4467.12 Median:5065.70 stddev:1402.36 (27.7%) stddev-sans-first:1371.04 00:07:32 INFO - PROCESS | 1671 | Values: 303.6 315.9 320.1 323.1 326.2 5051.1 5085.6 5152.2 5216.9 5315.0 4407.1 5029.3 5174.2 5261.1 5349.5 3407.8 3812.3 3924.5 3964.8 4039.8 3238.7 3398.1 3944.9 3974.9 3976.9 3017.8 3539.4 4022.9 4026.0 4047.0 3586.8 3742.6 3853.5 3977.0 4078.9 3133.9 3268.7 3516.4 3536.7 3588.9 4234.5 5102.9 5140.3 5143.7 5170.2 3453.3 3862.7 3912.1 4504.6 4728.2 4977.0 5581.3 5631.0 5653.8 5665.4 3949.5 3980.7 3983.3 3984.2 3997.1 4841.3 5374.0 5707.2 5797.5 5848.9 3235.0 3410.5 3785.4 3997.9 4006.5 3522.4 3608.8 3660.5 4033.8 4070.2 3736.4 4200.6 4638.7 4682.6 4698.2 4883.8 4927.0 5599.9 5678.0 5696.3 1200.4 1292.0 1372.2 1397.0 1412.0 812.7 853.8 869.8 871.7 901.2 3962.7 4345.0 5041.4 5128.8 5206.9 4367.2 4862.8 4866.0 5003.0 5382.9 4865.0 5157.6 5170.0 5208.8 5240.6 2680.2 2681.5 3006.1 3020.4 3020.9 4646.6 5655.7 5689.7 6015.2 6036.8 5304.9 5362.7 5433.7 5467.6 5832.5 4967.7 5752.6 5955.9 5978.0 5984.9 5326.2 5727.5 5737.7 5753.7 5796.6 5080.3 5256.8 5479.9 5603.9 5686.0 5512.8 5902.9 5972.3 5997.6 6034.4 3401.5 3432.3 3785.3 3961.5 3975.9 5444.8 5847.9 5854.5 5879.1 5890.5 4740.6 5034.2 5413.9 5639.4 5706.5 5260.7 5384.8 5677.7 5706.0 5718.3 5514.4 5579.9 5585.4 5587.8 5620.8 5341.4 5526.9 5580.8 5730.0 5752.6 4541.4 5349.7 5609.2 5646.9 5657.7 00:07:32 INFO - PROCESS | 1671 | 00:07:32 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17832.99 Median:36473.06 stddev:25605.44 (70.2%) stddev-sans-first:25644.78 00:07:32 INFO - PROCESS | 1671 | Values: 653.7 718.6 740.4 740.9 744.9 14391.7 14518.9 15101.2 15393.9 15628.8 14546.6 14661.3 14709.0 14732.3 14746.8 724.5 752.6 752.7 930.3 947.1 749.0 750.5 753.2 907.0 942.0 989.3 992.3 996.2 999.1 1002.7 986.2 987.9 988.0 990.5 994.6 567.0 713.1 734.2 735.2 736.4 14799.6 15136.8 15169.8 15178.8 15187.7 9997.3 12109.5 12201.5 12371.2 12728.2 10696.1 10716.8 10876.0 11294.5 11602.8 4026.2 4064.9 4189.1 4210.5 4213.5 79679.0 80487.2 80600.6 81736.6 82418.0 9455.5 9480.0 9667.9 9711.7 9754.3 21827.3 21988.8 21991.8 22019.4 22339.1 7305.9 7341.7 7375.9 7532.5 7684.4 3809.5 3915.3 3917.9 3940.4 3992.1 3402.3 3502.0 3506.5 3507.2 3507.6 637.6 641.7 646.5 648.1 658.5 487.6 513.9 514.4 544.4 562.1 7472.4 7528.5 7595.0 7659.3 8010.7 511.1 553.4 556.1 577.2 582.2 3452.3 3587.5 3633.8 3806.3 3834.4 87944.0 91576.6 92956.0 93157.1 93688.6 77973.6 77988.0 78207.0 81046.2 81509.6 72227.6 72317.6 72685.9 72917.0 72985.9 7117.2 7247.7 7272.5 7368.4 7401.9 45068.4 45146.0 45354.8 45433.5 45976.8 61836.7 62348.4 62898.1 63052.0 63116.7 9977.0 10023.9 10027.4 10227.1 10339.4 10273.2 10302.0 10595.9 10647.0 10967.2 9370.3 9478.3 9680.4 9944.3 10022.4 8485.4 8730.5 9342.6 9363.9 9893.6 9045.9 9397.4 9444.4 9639.8 9808.3 8277.4 8450.7 8519.1 8731.3 8754.1 6796.6 7939.2 7973.2 8193.8 8421.3 00:07:32 INFO - PROCESS | 1671 | -------- Summary: end -------- 00:07:32 INFO - PROCESS | 1671 | 00:07:33 INFO - TEST-INFO | 1671: exit 0 00:07:33 INFO - TEST-OK | dromaeo_css | took 1115853ms 00:07:33 INFO - TEST-START | kraken 00:07:33 INFO - operating with platform_type : mac_ 00:07:33 INFO - Initialising browser for kraken test... 00:07:33 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpX3YeQM/profile http://localhost:49279/getInfo.html) 00:07:35 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 00:07:35 INFO - PROCESS | 1677 | colorDepth:24 00:07:35 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 00:07:35 INFO - PROCESS | 1677 | __metrics 00:07:35 INFO - PROCESS | 1677 | 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" 00:07:35 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:07:36 INFO - TEST-INFO | 1677: exit 0 00:07:36 INFO - Browser initialized. 00:07:36 INFO - Running cycle 1/1 for kraken test... 00:07:36 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpX3YeQM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:07:48 INFO - PROCESS | 1679 | RSS: Main: 239001600 00:07:48 INFO - PROCESS | 1679 | 00:07:51 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 00:07:51 INFO - PROCESS | 1679 | RSS: Main: 287248384 00:07:51 INFO - PROCESS | 1679 | 00:07:54 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 00:07:55 INFO - PROCESS | 1679 | RSS: Main: 295796736 00:07:55 INFO - PROCESS | 1679 | 00:07:58 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 00:07:59 INFO - PROCESS | 1679 | RSS: Main: 383184896 00:07:59 INFO - PROCESS | 1679 | 00:08:01 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 00:08:02 INFO - PROCESS | 1679 | RSS: Main: 284078080 00:08:02 INFO - PROCESS | 1679 | 00:08:04 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:08:05 INFO - PROCESS | 1679 | RSS: Main: 287780864 00:08:05 INFO - PROCESS | 1679 | 00:08:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 00:08:08 INFO - PROCESS | 1679 | RSS: Main: 323588096 00:08:08 INFO - PROCESS | 1679 | 00:08:11 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 00:08:12 INFO - PROCESS | 1679 | RSS: Main: 326565888 00:08:12 INFO - PROCESS | 1679 | 00:08:15 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 00:08:16 INFO - PROCESS | 1679 | RSS: Main: 326889472 00:08:16 INFO - PROCESS | 1679 | 00:08:17 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:08:18 INFO - PROCESS | 1679 | RSS: Main: 313253888 00:08:18 INFO - PROCESS | 1679 | 00:08:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:08:21 INFO - PROCESS | 1679 | RSS: Main: 320577536 00:08:21 INFO - PROCESS | 1679 | 00:08:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:08:24 INFO - PROCESS | 1679 | RSS: Main: 322043904 00:08:24 INFO - PROCESS | 1679 | 00:08:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:08:27 INFO - PROCESS | 1679 | RSS: Main: 328024064 00:08:27 INFO - PROCESS | 1679 | 00:08:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:08:31 INFO - PROCESS | 1679 | RSS: Main: 325840896 00:08:31 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 00:08:33 INFO - PROCESS | 1679 | RSS: Main: 372785152 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | __start_tp_report 00:08:33 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 00:08:33 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 00:08:33 INFO - PROCESS | 1679 | |i|pagename|runs| 00:08:33 INFO - PROCESS | 1679 | |0;ai-astar;113;107;111;111;111;111;111;112;111;111 00:08:33 INFO - PROCESS | 1679 | |1;audio-beat-detection;156;115;141;196;118;137;135;118;166;116 00:08:33 INFO - PROCESS | 1679 | |2;audio-dft;186;188;185;184;184;184;183;184;183;219 00:08:33 INFO - PROCESS | 1679 | |3;audio-fft;78;73;55;126;57;63;57;60;111;55 00:08:33 INFO - PROCESS | 1679 | |4;audio-oscillator;92;100;132;80;81;104;140;107;80;101 00:08:33 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;87;85;89;84;87;85;87;84;90;85 00:08:33 INFO - PROCESS | 1679 | |6;imaging-darkroom;98;94;94;94;93;93;105;151;110;93 00:08:33 INFO - PROCESS | 1679 | |7;imaging-desaturate;82;84;84;84;83;83;92;136;85;84 00:08:33 INFO - PROCESS | 1679 | |8;json-parse-financial;57;57;59;55;56;58;56;116;55;54 00:08:33 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;62;69;68;76;72;71;66;68;66;66 00:08:33 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;85;85;83;79;83;85;85;85;85;86 00:08:33 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;146;147;146;136;135;129;136;150;241;162 00:08:33 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;166;160;161;163;160;162;166;166;217;198 00:08:33 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;65;67;66;65;66;65;63;65;65;65 00:08:33 INFO - PROCESS | 1679 | __end_tp_report 00:08:33 INFO - PROCESS | 1679 | __start_cc_report 00:08:33 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2298 00:08:33 INFO - PROCESS | 1679 | __end_cc_report 00:08:33 INFO - PROCESS | 1679 | __startTimestamp1463641713452__endTimestamp 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | ------- Summary: start ------- 00:08:33 INFO - PROCESS | 1679 | Number of tests: 14 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.00 stddev:1.52 (1.4%) stddev-sans-first:1.41 00:08:33 INFO - PROCESS | 1679 | Values: 113.0 107.0 111.0 111.0 111.0 111.0 111.0 112.0 111.0 111.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:139.00 stddev:26.40 (19.0%) stddev-sans-first:27.34 00:08:33 INFO - PROCESS | 1679 | Values: 156.0 115.0 141.0 196.0 118.0 137.0 135.0 118.0 166.0 116.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:188.00 Median:184.50 stddev:10.99 (6.0%) stddev-sans-first:11.64 00:08:33 INFO - PROCESS | 1679 | Values: 186.0 188.0 185.0 184.0 184.0 184.0 183.0 184.0 183.0 219.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:73.50 Median:58.50 stddev:25.18 (43.0%) stddev-sans-first:26.65 00:08:33 INFO - PROCESS | 1679 | Values: 78.0 73.0 55.0 126.0 57.0 63.0 57.0 60.0 111.0 55.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:101.70 Median:110.00 stddev:20.78 (18.9%) stddev-sans-first:21.74 00:08:33 INFO - PROCESS | 1679 | Values: 92.0 100.0 132.0 80.0 81.0 104.0 140.0 107.0 80.0 101.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:2.06 (2.4%) stddev-sans-first:2.17 00:08:33 INFO - PROCESS | 1679 | Values: 87.0 85.0 89.0 84.0 87.0 85.0 87.0 84.0 90.0 85.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:93.50 stddev:18.02 (19.3%) stddev-sans-first:19.04 00:08:33 INFO - PROCESS | 1679 | Values: 98.0 94.0 94.0 94.0 93.0 93.0 105.0 151.0 110.0 93.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:89.70 Median:84.00 stddev:16.50 (19.6%) stddev-sans-first:17.26 00:08:33 INFO - PROCESS | 1679 | Values: 82.0 84.0 84.0 84.0 83.0 83.0 92.0 136.0 85.0 84.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:56.50 stddev:18.93 (33.5%) stddev-sans-first:19.98 00:08:33 INFO - PROCESS | 1679 | Values: 57.0 57.0 59.0 55.0 56.0 58.0 56.0 116.0 55.0 54.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.50 stddev:3.89 (5.7%) stddev-sans-first:3.37 00:08:33 INFO - PROCESS | 1679 | Values: 62.0 69.0 68.0 76.0 72.0 71.0 66.0 68.0 66.0 66.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.00 stddev:2.02 (2.4%) stddev-sans-first:2.12 00:08:33 INFO - PROCESS | 1679 | Values: 85.0 85.0 83.0 79.0 83.0 85.0 85.0 85.0 85.0 86.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:146.50 stddev:32.40 (22.1%) stddev-sans-first:34.27 00:08:33 INFO - PROCESS | 1679 | Values: 146.0 147.0 146.0 136.0 135.0 129.0 136.0 150.0 241.0 162.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:166.00 stddev:19.43 (11.7%) stddev-sans-first:20.49 00:08:33 INFO - PROCESS | 1679 | Values: 166.0 160.0 161.0 163.0 160.0 162.0 166.0 166.0 217.0 198.0 00:08:33 INFO - PROCESS | 1679 | 00:08:33 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 00:08:33 INFO - PROCESS | 1679 | Values: 65.0 67.0 66.0 65.0 66.0 65.0 63.0 65.0 65.0 65.0 00:08:33 INFO - PROCESS | 1679 | -------- Summary: end -------- 00:08:33 INFO - PROCESS | 1679 | 00:08:34 INFO - TEST-INFO | 1679: exit 0 00:08:34 INFO - TEST-OK | kraken | took 60771ms 00:08:34 INFO - SUITE-END | took 1176s 00:08:34 INFO - Completed test suite (00:19:36) 00:08:34 INFO - javascript benchmark 00:08:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3465.9826700866493, 3475.8262086895634, 3502.4746288056795, 3507.736919731021, 3552.0054384772275, 5674.744636491358, 5688.260526131602, 5691.430856914307, 5759.078547432412, 5789.623674461162, 5527.281453411061, 5757.280339957505, 5776.480116789488, 5887.85280367991, 5913.438223368784, 5124.052050370675, 5157.6647517911215, 5238.161894096946, 5240.502152295526, 5344.946550534483, 5313.123334649778, 5360.142377219646, 5384.057789886774, 5388.622796404254, 5410.945890541103, 5183.663061900965, 5221.216817477371, 5231.372235331746, 5250.9474905250845, 5275.340582427197, 5187.714675692835, 5190.221466779975, 5246.265522826808, 5263.473652634744, 5320.095583750772, 5175.767090480937, 5177.896442071137, 5212.713300768456, 5221.791128354861, 5242.81650142247, 5687.431256874321, 5764.394738552433, 5771.711414429262, 5788.04497257952, 5792.420757924216, 5160.200168973815, 6143.419763699212, 6194.4425001749605, 6209.472194863442, 6234.501239900797, 5592.909382670381, 6105.847353816146, 6249.875002499924, 6336.239651241881, 6372.235331760172, 5881.529477641821, 5930.436608522184, 5971.940280597182, 5980.910286345708, 5982.431668991482, 6243.687815609293, 6280.905786413207, 6292.119103325538, 6306.779262725782, 6320.178376810985, 6232.626042437469, 6238.157848690459, 6249.312575616678, 6252.812415627538, 6266.216722909637, 6265.530085243625, 6327.17746692927, 6362.236531616236, 6375.808725738235, 6388.744450222032, 6112.169143789106, 6202.255729312511, 6279.560430769893, 6299.842503937438, 6307.4954003679595, 6277.843053923689, 6324.557280990379, 6335.398137177053, 6346.365363463709, 6346.587471814318, 4892.021595680878, 4973.35346404965, 4981.078500477351, 5004.249362595567, 5054.317667114966, 4508.549145085531, 4533.909321813545, 4535.659825513099, 4550.13547425988, 4587.610053145453, 5720.141978703145, 5755.0, 5759.568032397503, 5763.740631671585, 5774.191613174159, 6232.594881332699, 6245.843853903689, 6300.621962682254, 6317.399847014526, 6330.493560515148, 5762.683052432073, 5764.250647415809, 5770.13447982806, 5785.363610002896, 5800.0, 5442.346918369821, 5466.835994920159, 5470.398256191816, 5471.562275017989, 5475.06923822951, 6286.18279623646, 6304.873902521924, 6319.8104056878365, 6351.3331100235655, 6392.264889537762, 6356.586821856565, 6365.172527571386, 6365.427111559982, 6379.457746091449, 6405.423511883952, 6392.0, 6403.007533832263, 6406.0, 6413.134226879311, 6419.614823110628, 5090.770915308746, 6143.815685529441, 6176.042713379254, 6232.376762323732, 6317.873642527215, 5226.398964119175, 6255.522882832422, 6318.462930650762, 6324.3991820776955, 6334.303226644974, 6048.818535443944, 6211.472024877936, 6213.9067913980425, 6229.906551401823, 6236.688165591793, 5146.8934179151665, 6031.60794548353, 6101.277634935821, 6124.625058545733, 6195.752169913333, 5995.640261584319, 6055.485283750754, 6066.332703402534, 6066.636001839904, 6094.99496405025, 5997.0404735242155, 6081.787137450168, 6222.688865556614, 6225.719842607003, 6244.906326405016, 5979.611325263844, 6081.300650425081, 6099.542534309856, 6105.511559075175, 6128.233970753656], "value": 5748.21291297433, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.6301357542933, 526.673462453279, 527.9260903473512, 560.585166976437, 572.9743758671976, 25113.62329565058, 43044.0, 44471.555284447146, 44598.33102503461, 46970.295445568256, 45437.0, 45632.0, 45885.0, 45951.0, 45986.77006614962, 8694.478331300143, 9713.320067595254, 9921.900780992206, 9927.056929591714, 9932.0, 8816.779580510474, 11786.233894796853, 12051.975582075534, 12071.637850864488, 12300.478401927583, 11986.820197697041, 12055.578054768084, 12069.275843449377, 12069.698257543543, 12088.456019479101, 8753.912460875374, 11794.469248883821, 11958.103142264321, 12068.57759978401, 12088.972437342836, 11919.761604767857, 12018.87981120195, 12043.518259269618, 12046.0, 12057.758844823054, 41996.32014719408, 42346.57653423457, 42422.0, 42453.36319955203, 42524.78737606323, 3219.8551065202123, 3258.4786434170655, 3308.106811160973, 3319.0540695901796, 3497.8251087445524, 25831.870840645675, 29270.560941585893, 30325.241868953453, 31464.685353146633, 31475.898343558096, 2733.6992930777596, 2832.475991941477, 2867.985660071686, 2869.239651492356, 2889.2343528964843, 491446.85531446926, 493991.0, 494520.0, 494883.0, 495574.0, 62046.0, 62147.6892615534, 62258.0, 62316.0, 63118.36881631217, 65840.67079664666, 65850.0, 65882.34117658858, 65893.67053164703, 65979.0, 17115.22981465837, 17320.91339543294, 17321.826781732023, 17349.306027759, 17356.82643173552, 12060.457279422271, 12300.938495307466, 12979.0, 13219.405126769318, 13256.469741210436, 12972.935135324451, 15481.690366192839, 16868.493945181664, 16896.32414703398, 16974.32102715902, 4934.1118598651865, 6086.817395478142, 6326.620402775849, 6332.810015699536, 6513.348665133449, 927.3276874266238, 989.2926557511414, 1010.5604062232806, 1057.3444464432102, 1177.370106992772, 30248.0, 30576.8434908778, 30578.082657519866, 30588.0, 30704.0, 964.4840010594298, 1129.7966366054188, 1137.8122609769296, 1197.6167626359481, 1208.945597448117, 15258.0, 15408.460703875311, 15632.609184770472, 15806.209689515708, 15999.280032398337, 699532.0, 710959.0, 711966.0, 711984.0, 712574.0, 50902.49097508978, 64925.0, 64985.0, 65080.67459662671, 65342.019869701995, 1294.6504443800309, 1378.06980288307, 1434.6485111147836, 1522.4519173097676, 1554.2461905975592, 29932.97919872926, 30742.385152297276, 31223.0, 31511.84244078765, 32168.678313217504, 55395.723021384634, 55442.0, 56507.86984260374, 56560.43439565716, 56588.86822263614, 593732.0, 597640.0, 599386.0, 600212.0, 600214.0, 36081.639183607826, 36931.0, 42608.78695606502, 44295.0, 45700.0, 47636.761816190694, 48703.756481217366, 48960.510394895595, 49114.52656420313, 49159.016819664124, 37010.25979480449, 39894.60105399025, 40277.597224027384, 41565.96085097897, 42742.358864616435, 38452.230955380175, 38850.61149388583, 39389.0, 40374.0, 40400.39399408952, 38834.80582597069, 39222.411663824496, 39997.200055999296, 40087.79956100201, 40206.798966004986, 31054.0, 31607.683923161392, 34773.0, 34788.82605586956, 34814.65185348114, 24568.0, 25186.62220066664, 25482.490350192522, 25680.357991050372, 25722.35694107662], "value": 20953.863582842798, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.9860958726486, 397.9522457305124, 401.1835913915183, 404.141199950106, 425.3768229543718, 19295.710564341527, 19744.802551974477, 20549.075291611894, 20721.274755383565, 20793.480162995933, 20064.097115629796, 20171.798282017175, 21357.0, 21608.243711470102, 21629.242976495825, 5626.690532020737, 5931.911021334683, 5935.703214839241, 5936.6734829584575, 5944.762209511636, 4955.207166853304, 5660.886782264352, 5761.9423805762035, 5764.51001664859, 5893.58744887858, 5763.9711801440935, 5795.449432303924, 5805.274340707411, 5805.709714514257, 5806.44838740319, 5545.390007099216, 5788.942110578924, 5789.0, 5789.536837053047, 5798.942010579883, 5044.319016932704, 5066.214736715815, 5071.137906555894, 5078.5937125029905, 5079.8222062228015, 24547.754522454903, 25852.482950341077, 26140.73859261402, 26926.0, 27017.054403095797, 2193.6161171795034, 2201.636729939558, 2234.72065991751, 2363.8581685098948, 2372.7745864143053, 15107.697846043018, 15216.71811162057, 15274.167099947967, 15314.310856011507, 15447.768283475756, 1581.5571639940836, 1757.5254681236122, 1825.4341154242227, 1908.895010774407, 1928.6528424883516, 114980.0, 118912.0, 119467.40266298699, 120038.79961200451, 122381.38809305985, 9621.0, 11738.94130529342, 11741.354225517507, 11794.76410471803, 11815.577806549636, 15188.01277916932, 15262.771058434133, 15271.38914443432, 15283.770743438858, 15286.38854445832, 8580.613872375758, 8790.516521591246, 8800.295976321879, 8880.0, 8898.199162075443, 8689.782755431164, 8703.173198546128, 8714.259287960595, 8815.779605509786, 8842.646294148162, 4028.1943611277275, 4606.815727370935, 4683.578477936934, 4703.741294228782, 4788.0, 1713.7943446786464, 1886.820752028555, 1986.5430950881378, 1991.32295019694, 2011.577568710558, 640.0846789091551, 734.6179986407038, 819.5205804604374, 844.7127976488024, 1103.6523495099018, 19460.18758730798, 20352.89823550873, 20617.58764824695, 20712.378628640865, 20764.792352076285, 963.2631037256365, 983.331334692416, 983.6065573770549, 992.5186284651938, 1052.8683914510686, 10751.0324070834, 10781.665806092844, 10916.01755841978, 10930.344179349106, 10997.395143267036, 487040.0, 634818.0, 638382.0, 647362.0, 666066.0, 92582.0, 131026.37947241002, 133234.0, 135250.0, 143634.0, 971.4608392342318, 996.9252306077074, 1023.4268809466722, 1023.4575674892319, 1047.7171163785736, 14130.78803817923, 15934.60163495922, 16734.0, 18267.725984110515, 18996.335128270443, 54140.72929635327, 55100.72449637726, 55516.72241638766, 55924.48339824799, 56740.01409950632, 75190.87213691689, 75367.62316188384, 76397.85403218845, 77606.0, 78296.21703783117, 7135.571865688075, 7576.28025337592, 8047.51714897108, 8108.067572229033, 8563.828723425371, 11853.585124520945, 12942.935285323512, 13148.671283217996, 13313.933430332787, 13503.189808611514, 11062.17033722458, 11147.163962702993, 11333.469981552385, 11344.546218151181, 11390.658280251275, 8892.911070889208, 9244.537773111182, 9395.483248421402, 10351.327163734333, 11264.09887209005, 8692.565371731514, 10539.473026348498, 10959.452027398758, 11809.055275578105, 11853.644390668294, 8202.138775428493, 8253.422260441712, 8273.296769774637, 8491.490510569385, 8522.678984757373, 10425.687229383131, 11050.834237486604, 11394.94302528482, 11545.36500492481, 11598.5360585579], "value": 9627.796258851857, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.5262763288115, 1508.2458770614692, 1595.4415954415956, 1847.2610955617752, 1995.720599116124, 8045.275925166734, 8815.735527934165, 9100.180983711465, 9117.954410227949, 9229.307801914849, 8883.378163528556, 8947.284217262619, 9038.63845446183, 9040.050794666564, 9040.8643870342, 2411.7829395354415, 2447.4615584571457, 2449.6692946452267, 2456.520978409211, 2477.2939712182033, 2386.3484207550805, 2447.5472037673, 2449.632555116729, 2450.1179575352858, 2455.0548039004934, 2414.6015907375263, 2425.7331693513697, 2440.2801173653816, 2450.7304196538457, 2454.9386265343333, 2405.2663937499055, 2411.288669842401, 2411.7347091819975, 2418.6613874057557, 2437.24445421918, 2386.4033991502124, 2417.057347634424, 2420.8547487150827, 2421.539907417585, 2422.733499315074, 8749.03760586335, 8929.196372326456, 9022.368434209608, 9024.368294219406, 9026.54867256641, 5444.91832622511, 5629.352624448159, 5637.577181711347, 5651.632643878134, 5654.9717251413895, 2873.928151796201, 3610.4042832932537, 3658.268346330718, 3665.0, 3697.6487233712755, 935.1630290889684, 983.7835676151235, 1024.5901639344247, 1028.6348346337018, 1031.056583226347, 10821.621243256448, 12477.93761031189, 12504.187227830253, 12512.937435312766, 12517.874821251762, 3916.412538119248, 4681.204195286809, 4829.251466022772, 4846.103470857903, 4850.417949845971, 9529.52352382392, 9548.283878708986, 9549.331546791658, 9549.856752148724, 9556.0, 5467.617266791366, 5469.097598896137, 5469.72651367438, 5471.097268950579, 5480.424555421703, 4560.863174104782, 4693.20215563355, 4916.311716359713, 5036.874078148002, 5069.340985671839, 3916.5300163980505, 3942.1721438498234, 4006.779627120536, 4034.6570541503716, 4065.3901914712437, 1081.9567217311376, 1212.6968257935516, 1220.5170717578185, 1233.6114124050896, 1247.4760600547793, 623.6320570863122, 681.4990981628499, 927.3415874729018, 975.4342481360935, 985.2905907746555, 5297.947020529746, 5919.289685237799, 5951.434613711691, 6119.602225855305, 6181.196444462281, 503.50153348185034, 505.8887044850127, 520.7968892132071, 521.4342438454244, 648.3725848121155, 3946.980265098656, 4087.529934057562, 4123.525794533667, 4159.396887451303, 4198.782353117562, 10842.674719758419, 11224.494897729623, 11313.264637798517, 11322.207445478902, 11332.036776874058, 11143.665690029313, 11271.309303604525, 11319.603813866475, 11337.319760814378, 11341.886581134248, 8109.472884262504, 8455.830883382421, 8513.787155321166, 8565.614547345385, 8596.699115530926, 5174.197999310113, 5905.822825315247, 6208.596441231305, 6328.145700330397, 6396.712147953446, 8227.259546640831, 9934.503274836374, 10385.740356491147, 10530.157587392838, 10531.94734026325, 10383.2212584055, 10489.423081730578, 10521.736956576147, 10643.308184967953, 10696.839547406951, 7627.5042122261875, 7739.961300193463, 7796.220377962159, 7801.4929029612895, 7830.33442157423, 8384.58077096155, 8434.03008653988, 8436.915630843612, 8511.40420170607, 8537.701180458655, 7612.619369031416, 7638.427117966286, 7719.807004824925, 7874.055113386201, 7899.763007109796, 7255.963720181366, 7338.70645174187, 7400.666969986256, 7415.888761668687, 7512.511686740344, 6923.723051078102, 7170.318819712119, 7232.493725439169, 7236.312550307713, 7295.890561641473, 5053.393592768891, 5084.415292241291, 5105.106606343949, 5158.252053452154, 5302.965921645242, 5061.797528098982, 5070.822521211888, 5073.2897394365555, 5144.151215049625, 5436.755346009518], "value": 4598.90667976481, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.60379704485644, 315.91944054266156, 320.1132861972336, 323.14044641254253, 326.19918101061893, 5051.116054690433, 5085.593152547796, 5152.201408781641, 5216.930529241506, 5314.953742809017, 4407.11857628474, 5029.270755740425, 5174.223866420039, 5261.0793111205485, 5349.491798279158, 3407.761456698032, 3812.332841752696, 3924.4505769192338, 3964.810556832953, 4039.798010099498, 3238.740900727936, 3398.09950363154, 3944.901377465572, 3974.9006274843214, 3976.906350753537, 3017.838132319062, 3539.4159963605975, 4022.919541609166, 4026.0337518995525, 4046.9967366153796, 3586.7668601541077, 3742.6070262622306, 3853.460515527828, 3976.9801150994203, 4078.857239996586, 3133.85897634607, 3268.7385009199206, 3516.43737002081, 3536.6993805526563, 3588.9102772430897, 4234.513031001444, 5102.923456148161, 5140.278006867704, 5143.665661732013, 5170.224466330081, 3453.2575496268423, 3862.682390565666, 3912.080661044652, 4504.6396288296855, 4728.196206644879, 4977.004599080198, 5581.274434323512, 5630.971845140748, 5653.773849046074, 5665.433456654343, 3949.5260568731937, 3980.681545476355, 3983.322835118008, 3984.2230765000836, 3997.0821300450834, 4841.273808928654, 5373.9731301343245, 5707.229524014205, 5797.507211886995, 5848.853778655525, 3234.9971508832336, 3410.5225268462436, 3785.413260944558, 3997.860609726214, 4006.5392479864604, 3522.418800897823, 3608.7654302470255, 3660.4875317455576, 4033.8184781684895, 4070.2266569351727, 3736.3834967230646, 4200.6429453496185, 4638.675292729544, 4682.555157260055, 4698.1778188816825, 4883.804647814119, 4926.950730492649, 5599.89205990671, 5678.0, 5696.316442026983, 1200.3578085724096, 1292.043887523226, 1372.2246930484243, 1396.986030139706, 1411.9929400352933, 812.6871154605519, 853.8292341531718, 869.8173383589517, 871.6687658689657, 901.2159421303377, 3962.7424217425773, 4345.045369149009, 5041.420236672756, 5128.794733237686, 5206.891209060261, 4367.170237654836, 4862.832920099221, 4865.9756701216975, 5002.974985125051, 5382.896506216294, 4864.951350486521, 5157.561607263349, 5170.017696637627, 5208.817691380784, 5240.6374342670415, 2680.1691475642615, 2681.4771119631873, 3006.053093275611, 3020.3539071206246, 3020.909372718778, 4646.558576935186, 5655.72746132117, 5689.743961521742, 6015.218021657157, 6036.849078772978, 5304.912492938938, 5362.678239305654, 5433.712603874678, 5467.644603100785, 5832.475077243069, 4967.7019378837385, 5752.597318187771, 5955.880882382328, 5978.0, 5984.9401505986125, 5326.201069839555, 5727.541796656341, 5737.655740655574, 5753.683547404766, 5796.594238403271, 5080.288759573588, 5256.793356052728, 5479.890402192013, 5603.887922241451, 5685.971570142123, 5512.834614961397, 5902.911456328073, 5972.283326000907, 5997.640141591519, 6034.39656034393, 3401.5237866699176, 3432.262063656326, 3785.299719551892, 3961.504811898513, 3975.8807235782974, 5444.754986025718, 5847.94152058491, 5854.4730974212525, 5879.059910229699, 5890.4993075589055, 4740.5733483986605, 5034.244863270539, 5413.864653383697, 5639.379668236573, 5706.457886500776, 5260.73696315175, 5384.784608615611, 5677.687727175045, 5705.971470142623, 5718.313802343745, 5514.36584792754, 5579.944200557942, 5585.441455854382, 5587.832365029056, 5620.831375058755, 5341.359036915595, 5526.889462210653, 5580.804671836466, 5729.971350143389, 5752.597318187687, 4541.386912766734, 5349.705766182898, 5609.242752228397, 5646.85882852932, 5657.717114144194], "value": 4029.2532646625073, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.7221680785666, 718.5616773768, 740.3669862267758, 740.8666440040794, 744.8919906613542, 14391.712165756704, 14518.927405362985, 15101.24493775311, 15393.923030384847, 15628.84371156288, 14546.563603091898, 14661.266936653152, 14709.043912145704, 14732.337044833008, 14746.778798318008, 724.481995373308, 752.6048824367197, 752.7478294771258, 930.265090578444, 947.0955237747969, 749.0000848866742, 750.5159172333841, 753.2467532467532, 906.9501177435238, 941.9717408477757, 989.2877128467492, 992.270681049428, 996.217968894418, 999.0958182844537, 1002.6791426743445, 986.2455221755363, 987.8913319534845, 987.9604815807359, 990.479998001048, 994.5773046455228, 566.9546436285087, 713.1406654980735, 734.2033893225856, 735.1766022055278, 736.3704033051717, 14799.556013319618, 15136.848631513654, 15169.848301516953, 15178.772318415233, 15187.696246075127, 9997.250206234561, 12109.515619375214, 12201.511939522408, 12371.15458457796, 12728.23630582168, 10696.144308455305, 10716.78566428667, 10876.0, 11294.548218071268, 11602.825957610643, 4026.1545075534173, 4064.878053658395, 4189.120284740208, 4210.51579068408, 4213.47331583552, 79679.0, 80487.19512804855, 80600.59699701464, 81736.59131704363, 82418.0, 9455.52722363879, 9479.952600237093, 9667.903320966841, 9711.708648740549, 9754.268429867647, 21827.345179644635, 21988.780112198994, 21991.780082199293, 22019.449513761963, 22339.10643574239, 7305.853882922418, 7341.706331746777, 7375.852482950419, 7532.472726909064, 7684.423668224906, 3809.5047643806683, 3915.275674000319, 3917.8628747994253, 3940.4286378474962, 3992.121733218687, 3402.2685122698244, 3502.0194345583277, 3506.50908872758, 3507.2108775525303, 3507.5790905091553, 637.5664548107331, 641.7272659119856, 646.4698946863621, 648.144449326884, 658.4567731621336, 487.6196566677997, 513.9254808052812, 514.4406577278012, 544.3658138268952, 562.1325282059405, 7472.439567032386, 7528.51064680794, 7595.0, 7659.348955338859, 8010.679572817138, 511.10556526079364, 553.441024565192, 556.1490918894099, 577.2351634084854, 582.2256398989334, 3452.3440546296124, 3587.461880717913, 3633.8364773585254, 3806.333891568964, 3834.367329390675, 87944.0, 91576.6263506048, 92956.0, 93157.06842931619, 93688.59467107999, 77973.61013195012, 77988.0, 78207.0, 81046.18953810386, 81509.59245203737, 72227.55544889178, 72317.63841180761, 72685.90971135332, 72917.0, 72985.90521142186, 7117.181524124792, 7247.677154364439, 7272.454565907472, 7368.410527157922, 7401.851962960818, 45068.42260520866, 45146.0, 45354.77322613366, 45433.54566454293, 45976.77011614921, 61836.690816545626, 62348.37651623607, 62898.05652915118, 63052.0, 63116.68441657762, 9977.0, 10023.949880250551, 10027.398356098658, 10227.130693890806, 10339.379637221791, 10273.178145748472, 10302.0, 10595.894041059491, 10647.0, 10967.242197846195, 9370.34407591462, 9478.289128315542, 9680.370775899544, 9944.254180936603, 10022.448765317977, 8485.40602157843, 8730.453194026042, 9342.579583918876, 9363.906360936304, 9893.55479003461, 9045.864312035457, 9397.389169703947, 9444.386114902509, 9639.759006024906, 9808.313418060667, 8277.420580559303, 8450.661973521237, 8519.062903080532, 8731.301495880187, 8754.124587541195, 6796.592204467748, 7939.2060793920145, 7973.162817904268, 8193.754187374388, 8421.284190843848], "value": 6627.717900258455, "unit": "score"}], "name": "dromaeo_css", "value": 7226.631811053735, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 111.0, 111.0, 111.0, 111.0, 111.0, 112.0, 111.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 115.0, 141.0, 196.0, 118.0, 137.0, 135.0, 118.0, 166.0, 116.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 188.0, 185.0, 184.0, 184.0, 184.0, 183.0, 184.0, 183.0, 219.0], "value": 188.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 55.0, 126.0, 57.0, 63.0, 57.0, 60.0, 111.0, 55.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 100.0, 132.0, 80.0, 81.0, 104.0, 140.0, 107.0, 80.0, 101.0], "value": 101.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 84.0, 87.0, 85.0, 87.0, 84.0, 90.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 94.0, 94.0, 93.0, 93.0, 105.0, 151.0, 110.0, 93.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 84.0, 84.0, 83.0, 83.0, 92.0, 136.0, 85.0, 84.0], "value": 89.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 59.0, 55.0, 56.0, 58.0, 56.0, 116.0, 55.0, 54.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 69.0, 68.0, 76.0, 72.0, 71.0, 66.0, 68.0, 66.0, 66.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 79.0, 83.0, 85.0, 85.0, 85.0, 85.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 136.0, 135.0, 129.0, 136.0, 150.0, 241.0, 162.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 160.0, 161.0, 163.0, 160.0, 162.0, 166.0, 166.0, 217.0, 198.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 65.0, 66.0, 65.0, 63.0, 65.0, 65.0, 65.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1497.1000000000001, "alertThreshold": 2.0}]} 00:08:34 INFO - Return code: 0 00:08:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 00:08:34 ERROR - # TBPL SUCCESS # 00:08:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:08:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:08:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:08:34 INFO - 2016-05-19 00:08:34.768 screenresolution[1683:10261] starting screenresolution argv=screenresolution get 00:08:34 INFO - 2016-05-19 00:08:34.770 screenresolution[1683:10261] Display 0: 1600x1200x32@60 00:08:34 INFO - 2016-05-19 00:08:34.776 screenresolution[1684:10264] starting screenresolution argv=screenresolution list 00:08:34 INFO - Available Modes on Display 0 00:08:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:08:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:08:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:08:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:08:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:08:35 INFO - Intel Iris: 00:08:35 INFO - Chipset Model: Intel Iris 00:08:35 INFO - Type: GPU 00:08:35 INFO - Bus: Built-In 00:08:35 INFO - VRAM (Dynamic, Max): 1536 MB 00:08:35 INFO - Vendor: Intel (0x8086) 00:08:35 INFO - Device ID: 0x0a2e 00:08:35 INFO - Revision ID: 0x0009 00:08:35 INFO - Displays: 00:08:35 INFO - Display: 00:08:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:08:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:08:35 INFO - Display Serial Number: 1600x1200 60 00:08:35 INFO - Main Display: Yes 00:08:35 INFO - Mirror: Off 00:08:35 INFO - Online: Yes 00:08:35 INFO - Rotation: Supported 00:08:35 INFO - Return code: 0 00:08:35 INFO - Running post-action listener: _resource_record_post_action 00:08:35 INFO - ##### 00:08:35 INFO - ##### Finished run-tests step (success) 00:08:35 INFO - ##### 00:08:35 INFO - Running post-run listener: _resource_record_post_run 00:08:35 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36457984; Write bytes: 697396224; Read time: 250; Write time: 1925 00:08:35 INFO - TinderboxPrint: CPU usage
53.7% 00:08:35 INFO - TinderboxPrint: I/O read bytes / time
36,457,984 / 250 00:08:35 INFO - TinderboxPrint: I/O write bytes / time
697,396,224 / 1,925 00:08:35 INFO - TinderboxPrint: CPU idle
2,246.6 (46.3%) 00:08:35 INFO - TinderboxPrint: CPU system
965.8 (19.9%) 00:08:35 INFO - TinderboxPrint: CPU user
1,637.3 (33.8%) 00:08:35 INFO - TinderboxPrint: Swap in / out
427,724,800 / 0 00:08:35 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381102592; Write bytes: 375322112; Read time: 28399; Write time: 775 00:08:35 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35536384; Write bytes: 299197952; Read time: 238; Write time: 1097 00:08:35 INFO - Running post-run listener: _upload_blobber_files 00:08:35 INFO - Blob upload gear active. 00:08:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:08:35 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 00:08:35 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'] 00:08:35 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 00:08:35 INFO - (blobuploader) - INFO - Open directory for files ... 00:08:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:08:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:08:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:08:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:08:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:08:36 INFO - (blobuploader) - INFO - Done attempting. 00:08:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:08:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:08:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:08:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:08:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:08:38 INFO - (blobuploader) - INFO - Done attempting. 00:08:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:08:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:08:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:08:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:08:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:08:40 INFO - (blobuploader) - INFO - Done attempting. 00:08:40 INFO - (blobuploader) - INFO - Iteration through files over. 00:08:40 INFO - Return code: 0 00:08:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:08:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:08:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0616b18963145ba93f0f892f123be71719ddd5b74f09c5699e90c6dc8843317f7e9b6b9096add2f5ec0f25c024a78c6353bf176fd56e28e5a6f5ce274dbcc1a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd9e191280920964205fa4954fea384f32fd4c3a0ddf39dcecf475d5e2d6852c51301de3ba9e69764f1549f3e2213a10706405274691f85d7dd9f6643eec5eba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/331f1bb802d09f0d91e9fa6b922d09fdaef4537a8766b14b69189b22526f45bef246d1eaabcfeb79dce450324ed1b6b731cee9c09ce2dbff66a296206ca3b5f6"} 00:08:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:08:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:08:40 INFO - Contents: 00:08:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0616b18963145ba93f0f892f123be71719ddd5b74f09c5699e90c6dc8843317f7e9b6b9096add2f5ec0f25c024a78c6353bf176fd56e28e5a6f5ce274dbcc1a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd9e191280920964205fa4954fea384f32fd4c3a0ddf39dcecf475d5e2d6852c51301de3ba9e69764f1549f3e2213a10706405274691f85d7dd9f6643eec5eba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/331f1bb802d09f0d91e9fa6b922d09fdaef4537a8766b14b69189b22526f45bef246d1eaabcfeb79dce450324ed1b6b731cee9c09ce2dbff66a296206ca3b5f6"} 00:08:40 INFO - Running post-run listener: copy_logs_to_upload_dir 00:08:40 INFO - Copying logs to upload dir... 00:08:40 INFO - mkdir: /builds/slave/test/build/upload/logs 00:08:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.234975 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-19 00:08:40.677097) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 00:08:40.681698) ========= 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.Friofd4ail/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.3kWM1gRTxD/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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/0616b18963145ba93f0f892f123be71719ddd5b74f09c5699e90c6dc8843317f7e9b6b9096add2f5ec0f25c024a78c6353bf176fd56e28e5a6f5ce274dbcc1a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd9e191280920964205fa4954fea384f32fd4c3a0ddf39dcecf475d5e2d6852c51301de3ba9e69764f1549f3e2213a10706405274691f85d7dd9f6643eec5eba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/331f1bb802d09f0d91e9fa6b922d09fdaef4537a8766b14b69189b22526f45bef246d1eaabcfeb79dce450324ed1b6b731cee9c09ce2dbff66a296206ca3b5f6"} build_url:https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011122 build_url: 'https://queue.taskcluster.net/v1/task/YsCbSC-MSCe9zS3Zkt9wqw/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/0616b18963145ba93f0f892f123be71719ddd5b74f09c5699e90c6dc8843317f7e9b6b9096add2f5ec0f25c024a78c6353bf176fd56e28e5a6f5ce274dbcc1a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd9e191280920964205fa4954fea384f32fd4c3a0ddf39dcecf475d5e2d6852c51301de3ba9e69764f1549f3e2213a10706405274691f85d7dd9f6643eec5eba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/331f1bb802d09f0d91e9fa6b922d09fdaef4537a8766b14b69189b22526f45bef246d1eaabcfeb79dce450324ed1b6b731cee9c09ce2dbff66a296206ca3b5f6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 00:08:40.712478) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 00:08:40.712774) ========= 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.Friofd4ail/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.3kWM1gRTxD/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 00:08:40.760958) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 00:08:40.761249) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 00:08:40.764583) ========= ========= Total master_lag: 0.21 =========