builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0126 starttime: 1463142268.45 results: success (0) buildid: 20160513030539 builduid: 82338dfb46ec444d8bb6ac0a524a2cd0 revision: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:28.451063) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:28.451510) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:28.451801) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:28.470418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:28.470808) ========= 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.rJ59TefqHT/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.uJDubBCaWc/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-13 05:24:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-05-13 05:24:28 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099830 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:28.584856) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:28.585182) ========= 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.rJ59TefqHT/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.uJDubBCaWc/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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.020436 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:28.634048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:28.634450) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rJ59TefqHT/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.uJDubBCaWc/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-13 05:24:28,705 truncating revision to first 12 chars 2016-05-13 05:24:28,705 Setting DEBUG logging. 2016-05-13 05:24:28,705 attempt 1/10 2016-05-13 05:24:28,705 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5a2deb5a9b09?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 05:24:29,373 unpacking tar archive at: mozilla-central-5a2deb5a9b09/testing/mozharness/ program finished with exit code 0 elapsedTime=0.910674 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:29.562043) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:29.562368) ========= script_repo_revision: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:29.562873) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:29.563149) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 05:24:29.576667) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-13 05:24:29.576984) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rJ59TefqHT/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.uJDubBCaWc/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:24:29 INFO - MultiFileLogger online at 20160513 05:24:29 in /builds/slave/test 05:24:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:24:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:24:29 INFO - {'append_to_log': False, 05:24:29 INFO - 'base_work_dir': '/builds/slave/test', 05:24:29 INFO - 'blob_upload_branch': 'Firefox', 05:24:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:24:29 INFO - 'branch': 'Firefox', 05:24:29 INFO - 'buildbot_json_path': 'buildprops.json', 05:24:29 INFO - 'config_files': ('talos/mac_config.py',), 05:24:29 INFO - 'default_actions': ('clobber', 05:24:29 INFO - 'read-buildbot-config', 05:24:29 INFO - 'download-and-extract', 05:24:29 INFO - 'populate-webroot', 05:24:29 INFO - 'create-virtualenv', 05:24:29 INFO - 'install', 05:24:29 INFO - 'run-tests'), 05:24:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:24:29 INFO - 'download_minidump_stackwalk': True, 05:24:29 INFO - 'download_symbols': 'ondemand', 05:24:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:24:29 INFO - 'tooltool.py': '/tools/tooltool.py', 05:24:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:24:29 INFO - '/tools/misc-python/virtualenv.py')}, 05:24:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:24:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:24:29 INFO - 'installer_path': 'installer.exe', 05:24:29 INFO - 'log_level': 'info', 05:24:29 INFO - 'log_name': 'talos', 05:24:29 INFO - 'log_to_console': True, 05:24:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:24:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:24:29 INFO - 'opt_config_files': (), 05:24:29 INFO - 'pip_index': False, 05:24:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:24:29 INFO - 'cmd': ('bash', 05:24:29 INFO - '-c', 05:24:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:24:29 INFO - 'enabled': True, 05:24:29 INFO - 'halt_on_failure': False, 05:24:29 INFO - 'name': 'check_screen_resolution'},), 05:24:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:24:29 INFO - 'cmd': ('bash', 05:24:29 INFO - '-c', 05:24:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:24:29 INFO - 'enabled': True, 05:24:29 INFO - 'halt_on_failure': False, 05:24:29 INFO - 'name': 'check_screen_resolution'},), 05:24:29 INFO - 'run_cmd_checks_enabled': True, 05:24:29 INFO - 'sps_profile': False, 05:24:29 INFO - 'sps_profile_interval': 0, 05:24:29 INFO - 'suite': 'dromaeojs', 05:24:29 INFO - 'system_bits': '32', 05:24:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:24:29 INFO - 'title': 't-yosemite-r7-0126', 05:24:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:24:29 INFO - 'use_talos_json': True, 05:24:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:24:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:24:29 INFO - 'work_dir': 'build'} 05:24:29 INFO - ##### 05:24:29 INFO - ##### Running clobber step. 05:24:29 INFO - ##### 05:24:29 INFO - Running pre-action listener: _resource_record_pre_action 05:24:29 INFO - Running main action method: clobber 05:24:29 INFO - rmtree: /builds/slave/test/build 05:24:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:24:31 INFO - Running post-action listener: _resource_record_post_action 05:24:31 INFO - ##### 05:24:31 INFO - ##### Finished clobber step (success) 05:24:31 INFO - ##### 05:24:31 INFO - ##### 05:24:31 INFO - ##### Running read-buildbot-config step. 05:24:31 INFO - ##### 05:24:31 INFO - Running pre-action listener: _resource_record_pre_action 05:24:31 INFO - Running main action method: read_buildbot_config 05:24:31 INFO - Using buildbot properties: 05:24:31 INFO - { 05:24:31 INFO - "project": "", 05:24:31 INFO - "product": "firefox", 05:24:31 INFO - "script_repo_revision": "production", 05:24:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:24:31 INFO - "repository": "", 05:24:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:24:31 INFO - "buildid": "20160513030539", 05:24:31 INFO - "stage_platform": "macosx64", 05:24:31 INFO - "basedir": "/builds/slave/test", 05:24:31 INFO - "nightly_build": "True", 05:24:31 INFO - "buildnumber": 240, 05:24:31 INFO - "slavename": "t-yosemite-r7-0126", 05:24:31 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:24:31 INFO - "platform": "yosemite_r7", 05:24:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:24:31 INFO - "branch": "mozilla-central", 05:24:31 INFO - "repo_path": "mozilla-central", 05:24:31 INFO - "revision": "5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4", 05:24:31 INFO - "pgo_build": "False", 05:24:31 INFO - "builduid": "82338dfb46ec444d8bb6ac0a524a2cd0", 05:24:31 INFO - "slavebuilddir": "test" 05:24:31 INFO - } 05:24:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:24:31 INFO - Running post-action listener: _resource_record_post_action 05:24:31 INFO - ##### 05:24:31 INFO - ##### Finished read-buildbot-config step (success) 05:24:31 INFO - ##### 05:24:31 INFO - ##### 05:24:31 INFO - ##### Running download-and-extract step. 05:24:31 INFO - ##### 05:24:31 INFO - Running pre-action listener: _resource_record_pre_action 05:24:31 INFO - Running main action method: download_and_extract 05:24:31 INFO - mkdir: /builds/slave/test/build/tests 05:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:31 INFO - https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:24:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:24:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:24:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/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 05:24:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/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 05:24:32 INFO - Downloaded 1199 bytes. 05:24:32 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:24:32 INFO - Using the following test package requirements: 05:24:32 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:24:32 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:24:32 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:24:32 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:24:32 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:24:32 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:24:32 INFO - u'jsshell-mac.zip'], 05:24:32 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:24:32 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:24:32 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:24:32 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:24:32 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:24:32 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:24:32 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:24:32 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:24:32 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:24:32 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:24:32 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:24:32 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:32 INFO - https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:24:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:24:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:24:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/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 05:24:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/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 05:24:33 INFO - Downloaded 18153965 bytes. 05:24:33 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'] 05:24:33 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 05:24:36 INFO - Return code: 0 05:24:36 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 05:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:36 INFO - https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:24:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:24:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:24:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/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 05:24:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/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 05:24:38 INFO - Downloaded 18153965 bytes. 05:24:38 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'] 05:24:38 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 05:24:41 INFO - Return code: 0 05:24: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')]} 05:24:41 INFO - https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:24:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:24:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:24:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/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 05:24:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/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 05:24:43 INFO - Downloaded 10840239 bytes. 05:24:43 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'] 05:24:43 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 05:24:43 INFO - Return code: 0 05:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:43 INFO - https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:24:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:24:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:24:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:24:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WbLb85BoTXCvo6eUqHoI1Q/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 05:24:50 INFO - Downloaded 110193637 bytes. 05:24:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:24:50 INFO - mkdir: /builds/slave/test/properties 05:24:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:24:50 INFO - Writing to file /builds/slave/test/properties/build_url 05:24:50 INFO - Contents: 05:24:50 INFO - build_url:https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:24:51 INFO - Running post-action listener: _resource_record_post_action 05:24:51 INFO - Running post-action listener: set_extra_try_arguments 05:24:51 INFO - ##### 05:24:51 INFO - ##### Finished download-and-extract step (success) 05:24:51 INFO - ##### 05:24:51 INFO - ##### 05:24:51 INFO - ##### Running populate-webroot step. 05:24:51 INFO - ##### 05:24:51 INFO - Running pre-action listener: _resource_record_pre_action 05:24:51 INFO - Running main action method: populate_webroot 05:24:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:24:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:24:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:24:51 INFO - '--tppagecycles', 05:24:51 INFO - '7'], 05:24:51 INFO - 'tests': ['tsvgm']}}, 05:24:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:24:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:24:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:24:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:24:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:51 INFO - 'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:51 INFO - 'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['damp', 'tps']}, 05:24:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:51 INFO - 'tests': ['damp', 'tps']}, 05:24:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['dromaeo_dom']}, 05:24:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:24:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['a11yr', 05:24:51 INFO - 'ts_paint', 05:24:51 INFO - 'tpaint', 05:24:51 INFO - 'sessionrestore', 05:24:51 INFO - 'sessionrestore_no_auto_restore', 05:24:51 INFO - 'tabpaint']}, 05:24:51 INFO - 'other-e10s': {'tests': ['a11yr', 05:24:51 INFO - 'ts_paint', 05:24:51 INFO - 'tpaint', 05:24:51 INFO - 'sessionrestore', 05:24:51 INFO - 'sessionrestore_no_auto_restore', 05:24:51 INFO - 'tabpaint']}, 05:24:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:24:51 INFO - 'ts_paint', 05:24:51 INFO - 'tpaint', 05:24:51 INFO - 'sessionrestore', 05:24:51 INFO - 'sessionrestore_no_auto_restore', 05:24:51 INFO - 'tabpaint']}, 05:24:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:24:51 INFO - 'ts_paint', 05:24:51 INFO - 'tpaint', 05:24:51 INFO - 'sessionrestore', 05:24:51 INFO - 'sessionrestore_no_auto_restore', 05:24:51 INFO - 'tabpaint']}, 05:24:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['a11yr', 05:24:51 INFO - 'ts_paint', 05:24:51 INFO - 'tpaint', 05:24:51 INFO - 'sessionrestore', 05:24:51 INFO - 'sessionrestore_no_auto_restore', 05:24:51 INFO - 'tabpaint']}, 05:24:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['a11yr', 05:24:51 INFO - 'ts_paint', 05:24:51 INFO - 'tpaint', 05:24:51 INFO - 'sessionrestore', 05:24:51 INFO - 'sessionrestore_no_auto_restore', 05:24:51 INFO - 'tabpaint']}, 05:24:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['tsvgx', 05:24:51 INFO - 'tsvgr_opacity', 05:24:51 INFO - 'tart', 05:24:51 INFO - 'tscrollx', 05:24:51 INFO - 'cart']}, 05:24:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:24:51 INFO - 'tsvgr_opacity', 05:24:51 INFO - 'tart', 05:24:51 INFO - 'tscrollx', 05:24:51 INFO - 'cart']}, 05:24:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:51 INFO - 'talos_options': ['--disable-e10s'], 05:24:51 INFO - 'tests': ['tp5o']}, 05:24:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:51 INFO - 'tests': ['tp5o']}, 05:24:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:51 INFO - 'talos_options': ['--disable-e10s', 05:24:51 INFO - '--xperf_path', 05:24:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:51 INFO - 'tests': ['tp5n']}, 05:24:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:51 INFO - 'talos_options': ['--xperf_path', 05:24:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:51 INFO - 'tests': ['tp5n']}}, 05:24:51 INFO - 'talos.zip': {'path': '', 05:24:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:24:51 INFO - Running post-action listener: _resource_record_post_action 05:24:51 INFO - ##### 05:24:51 INFO - ##### Finished populate-webroot step (success) 05:24:51 INFO - ##### 05:24:51 INFO - ##### 05:24:51 INFO - ##### Running create-virtualenv step. 05:24:51 INFO - ##### 05:24:51 INFO - Running pre-action listener: _resource_record_pre_action 05:24:51 INFO - Running main action method: create_virtualenv 05:24:51 INFO - Creating virtualenv /builds/slave/test/build/venv 05:24:51 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 05:24:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:24:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:24:51 INFO - Using real prefix '/tools/python27' 05:24:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:24:52 INFO - Installing distribute.............................................................................................................................................................................................done. 05:24:55 INFO - Installing pip.................done. 05:24:55 INFO - Return code: 0 05:24:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:55 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 0x10c58ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c591570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9070d23df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, '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.rJ59TefqHT/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.uJDubBCaWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:24:55 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 05:24:55 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 05:24:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:24:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:55 INFO - 'HOME': '/Users/cltbld', 05:24:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:55 INFO - 'LOGNAME': 'cltbld', 05:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:55 INFO - 'MOZ_NO_REMOTE': '1', 05:24:55 INFO - 'NO_EM_RESTART': '1', 05:24:55 INFO - 'PAGER': '/bin/cat', 05:24:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:55 INFO - 'PWD': '/builds/slave/test', 05:24:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:55 INFO - 'SHELL': '/bin/bash', 05:24:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:24:55 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:24:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:55 INFO - 'USER': 'cltbld', 05:24:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:55 INFO - 'XPC_FLAGS': '0x0', 05:24:55 INFO - 'XPC_SERVICE_NAME': '0', 05:24:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:55 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 05:24:55 INFO - Cleaning up... 05:24:55 INFO - Return code: 0 05:24:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:55 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 0x10c58ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c591570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9070d23df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, '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.rJ59TefqHT/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.uJDubBCaWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:24:55 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 05:24:55 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 05:24:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:24:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:55 INFO - 'HOME': '/Users/cltbld', 05:24:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:55 INFO - 'LOGNAME': 'cltbld', 05:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:55 INFO - 'MOZ_NO_REMOTE': '1', 05:24:55 INFO - 'NO_EM_RESTART': '1', 05:24:55 INFO - 'PAGER': '/bin/cat', 05:24:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:55 INFO - 'PWD': '/builds/slave/test', 05:24:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:55 INFO - 'SHELL': '/bin/bash', 05:24:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:24:55 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:24:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:55 INFO - 'USER': 'cltbld', 05:24:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:55 INFO - 'XPC_FLAGS': '0x0', 05:24:55 INFO - 'XPC_SERVICE_NAME': '0', 05:24:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:55 INFO - Downloading/unpacking psutil>=3.1.1 05:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:24:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:58 INFO - Installing collected packages: psutil 05:24:58 INFO - Running setup.py install for psutil 05:24:58 INFO - building 'psutil._psutil_osx' extension 05:24:58 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 05:24:59 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 05:24:59 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 05:24:59 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 05:24:59 INFO - building 'psutil._psutil_posix' extension 05:24:59 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 05:24:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:24:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:24:59 INFO - ^ 05:24:59 INFO - 1 warning generated. 05:24:59 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 05:24:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:59 INFO - Successfully installed psutil 05:24:59 INFO - Cleaning up... 05:24:59 INFO - Return code: 0 05:24:59 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c58ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c591570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9070d23df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, '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.rJ59TefqHT/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.uJDubBCaWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:24: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', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 05:24: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 mozsystemmonitor==0.1 05:24:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:24:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:59 INFO - 'HOME': '/Users/cltbld', 05:24:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:59 INFO - 'LOGNAME': 'cltbld', 05:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:59 INFO - 'MOZ_NO_REMOTE': '1', 05:24:59 INFO - 'NO_EM_RESTART': '1', 05:24:59 INFO - 'PAGER': '/bin/cat', 05:24:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:59 INFO - 'PWD': '/builds/slave/test', 05:24:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:59 INFO - 'SHELL': '/bin/bash', 05:24:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:24:59 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:24:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:59 INFO - 'USER': 'cltbld', 05:24:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:59 INFO - 'XPC_FLAGS': '0x0', 05:24:59 INFO - 'XPC_SERVICE_NAME': '0', 05:24:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:59 INFO - Downloading/unpacking mozsystemmonitor==0.1 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:25:02 INFO - Downloading mozsystemmonitor-0.1.tar.gz 05:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 05:25:02 INFO - Installing collected packages: mozsystemmonitor 05:25:02 INFO - Running setup.py install for mozsystemmonitor 05:25:02 INFO - Successfully installed mozsystemmonitor 05:25:02 INFO - Cleaning up... 05:25:02 INFO - Return code: 0 05:25:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:02 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 0x10c58ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c591570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9070d23df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, '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.rJ59TefqHT/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.uJDubBCaWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:25:02 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 05:25:02 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 05:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:02 INFO - 'HOME': '/Users/cltbld', 05:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:02 INFO - 'LOGNAME': 'cltbld', 05:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:02 INFO - 'MOZ_NO_REMOTE': '1', 05:25:02 INFO - 'NO_EM_RESTART': '1', 05:25:02 INFO - 'PAGER': '/bin/cat', 05:25:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:02 INFO - 'PWD': '/builds/slave/test', 05:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:02 INFO - 'SHELL': '/bin/bash', 05:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:25:02 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:02 INFO - 'USER': 'cltbld', 05:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:02 INFO - 'XPC_FLAGS': '0x0', 05:25:02 INFO - 'XPC_SERVICE_NAME': '0', 05:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25:05 INFO - Downloading blobuploader-1.2.4.tar.gz 05:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:25:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:25:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:25:06 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 05:25:06 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 05:25:06 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 05:25:06 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 05:25:06 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 05:25:06 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 05:25:06 INFO - Downloading docopt-0.6.1.tar.gz 05:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:25:06 INFO - Installing collected packages: blobuploader, requests, docopt 05:25:06 INFO - Running setup.py install for blobuploader 05:25:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:25:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:25:06 INFO - Running setup.py install for requests 05:25:07 INFO - Running setup.py install for docopt 05:25:07 INFO - Successfully installed blobuploader requests docopt 05:25:07 INFO - Cleaning up... 05:25:07 INFO - Return code: 0 05:25:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25:07 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')]} 05:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:07 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')]} 05:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:07 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 0x10c58ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c591570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9070d23df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, '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.rJ59TefqHT/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.uJDubBCaWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:25:07 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 05:25:07 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 05:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:07 INFO - 'HOME': '/Users/cltbld', 05:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:07 INFO - 'LOGNAME': 'cltbld', 05:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:07 INFO - 'MOZ_NO_REMOTE': '1', 05:25:07 INFO - 'NO_EM_RESTART': '1', 05:25:07 INFO - 'PAGER': '/bin/cat', 05:25:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:07 INFO - 'PWD': '/builds/slave/test', 05:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:07 INFO - 'SHELL': '/bin/bash', 05:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:25:07 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:07 INFO - 'USER': 'cltbld', 05:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:07 INFO - 'XPC_FLAGS': '0x0', 05:25:07 INFO - 'XPC_SERVICE_NAME': '0', 05:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:25:07 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-rjCqbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:25:07 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-wwKYm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:25:07 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-RnZAQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:25:08 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-dxkDy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:25:08 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-FRJD9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:25:08 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-m8skUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:25:08 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-hJC5zC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:25:08 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-wp7Cm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:25:08 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-TUaLQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:25:08 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-ywnu3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:25:08 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-vBbKeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:25:08 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-kCCvWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:25:09 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-xYiE1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:25:09 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-1TZRmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:09 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-0qhETf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:25:09 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-jKNxgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:25:09 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-vIFX9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:25:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:25:09 INFO - Running setup.py install for manifestparser 05:25:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:25:09 INFO - Running setup.py install for mozcrash 05:25:09 INFO - Running setup.py install for mozdebug 05:25:09 INFO - Running setup.py install for mozdevice 05:25:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:25:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:25:10 INFO - Running setup.py install for mozfile 05:25:10 INFO - Running setup.py install for mozhttpd 05:25:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:25:10 INFO - Running setup.py install for mozinfo 05:25:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:25:10 INFO - Running setup.py install for mozInstall 05:25:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:25:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:25:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:25:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:25:10 INFO - Running setup.py install for mozleak 05:25:10 INFO - Running setup.py install for mozlog 05:25:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:25:11 INFO - Running setup.py install for moznetwork 05:25:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:25:11 INFO - Running setup.py install for mozprocess 05:25:11 INFO - Running setup.py install for mozprofile 05:25:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:25:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:25:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:25:11 INFO - Running setup.py install for mozrunner 05:25:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:25:11 INFO - Running setup.py install for mozscreenshot 05:25:11 INFO - Running setup.py install for moztest 05:25:11 INFO - Running setup.py install for mozversion 05:25:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:25:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:25:12 INFO - Cleaning up... 05:25:12 INFO - Return code: 0 05:25:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:12 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 0x10c58ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c591570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9070d23df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, '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.rJ59TefqHT/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.uJDubBCaWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:25:12 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 05:25:12 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 05:25:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:25:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:12 INFO - 'HOME': '/Users/cltbld', 05:25:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:12 INFO - 'LOGNAME': 'cltbld', 05:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:12 INFO - 'MOZ_NO_REMOTE': '1', 05:25:12 INFO - 'NO_EM_RESTART': '1', 05:25:12 INFO - 'PAGER': '/bin/cat', 05:25:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:12 INFO - 'PWD': '/builds/slave/test', 05:25:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:12 INFO - 'SHELL': '/bin/bash', 05:25:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:25:12 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:25:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:12 INFO - 'USER': 'cltbld', 05:25:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:12 INFO - 'XPC_FLAGS': '0x0', 05:25:12 INFO - 'XPC_SERVICE_NAME': '0', 05:25:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:25:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-olwYhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:25:12 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)) 05:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:25:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-__RJlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:25:12 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)) 05:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:25:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-dulVlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:25:12 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)) 05:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:25:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-pCW42Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:25:12 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)) 05:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:25:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-VhaqZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:25:12 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)) 05:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:25:12 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-bRZPEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-y7tM5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-wiBu1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-2rhOI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-bUriKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-wMFhdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-B8at10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-l_Povb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-1GLdf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-_mzXEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:25:13 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)) 05:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:25:13 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-eX3Rt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:25:14 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)) 05:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:25:14 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-Bz0pic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:25:14 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)) 05:25:14 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)) 05:25:14 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)) 05:25:14 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)) 05:25:14 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)) 05:25:14 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)) 05:25:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25:16 INFO - Downloading blessings-1.6.tar.gz 05:25:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:25:16 INFO - Installing collected packages: blessings 05:25:16 INFO - Running setup.py install for blessings 05:25:16 INFO - Successfully installed blessings 05:25:16 INFO - Cleaning up... 05:25:17 INFO - Return code: 0 05:25:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:25:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:25:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:25:17 INFO - Reading from file tmpfile_stdout 05:25:17 INFO - Current package versions: 05:25:17 INFO - blessings == 1.6 05:25:17 INFO - blobuploader == 1.2.4 05:25:17 INFO - docopt == 0.6.1 05:25:17 INFO - manifestparser == 1.1 05:25:17 INFO - mozInstall == 1.12 05:25:17 INFO - mozcrash == 0.17 05:25:17 INFO - mozdebug == 0.1 05:25:17 INFO - mozdevice == 0.48 05:25:17 INFO - mozfile == 1.2 05:25:17 INFO - mozhttpd == 0.7 05:25:17 INFO - mozinfo == 0.9 05:25:17 INFO - mozleak == 0.1 05:25:17 INFO - mozlog == 3.2 05:25:17 INFO - moznetwork == 0.27 05:25:17 INFO - mozprocess == 0.22 05:25:17 INFO - mozprofile == 0.28 05:25:17 INFO - mozrunner == 6.11 05:25:17 INFO - mozscreenshot == 0.1 05:25:17 INFO - mozsystemmonitor == 0.1 05:25:17 INFO - moztest == 0.7 05:25:17 INFO - mozversion == 1.4 05:25:17 INFO - psutil == 3.1.1 05:25:17 INFO - requests == 1.2.3 05:25:17 INFO - wsgiref == 0.1.2 05:25:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:17 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 0x10c58ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c591570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9070d23df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, '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.rJ59TefqHT/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.uJDubBCaWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:25:17 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 05:25:17 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 05:25:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:25:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:17 INFO - 'HOME': '/Users/cltbld', 05:25:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:17 INFO - 'LOGNAME': 'cltbld', 05:25:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:17 INFO - 'MOZ_NO_REMOTE': '1', 05:25:17 INFO - 'NO_EM_RESTART': '1', 05:25:17 INFO - 'PAGER': '/bin/cat', 05:25:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:17 INFO - 'PWD': '/builds/slave/test', 05:25:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:17 INFO - 'SHELL': '/bin/bash', 05:25:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:25:17 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:25:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:17 INFO - 'USER': 'cltbld', 05:25:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:17 INFO - 'XPC_FLAGS': '0x0', 05:25:17 INFO - 'XPC_SERVICE_NAME': '0', 05:25:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:17 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)) 05:25:17 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)) 05:25:17 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)) 05:25:17 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)) 05:25:17 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)) 05:25:17 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)) 05:25:17 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)) 05:25:17 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)) 05:25:17 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)) 05:25:17 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)) 05:25:17 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)) 05:25:17 INFO - Cleaning up... 05:25:17 INFO - Return code: 0 05:25:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c58ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c591570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9070d23df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, '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.rJ59TefqHT/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.uJDubBCaWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:25: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', 'jsonschema'] in /builds/slave/test/build 05:25: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 jsonschema 05:25:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:25:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:17 INFO - 'HOME': '/Users/cltbld', 05:25:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:17 INFO - 'LOGNAME': 'cltbld', 05:25:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:17 INFO - 'MOZ_NO_REMOTE': '1', 05:25:17 INFO - 'NO_EM_RESTART': '1', 05:25:17 INFO - 'PAGER': '/bin/cat', 05:25:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:17 INFO - 'PWD': '/builds/slave/test', 05:25:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:17 INFO - 'SHELL': '/bin/bash', 05:25:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:25:17 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:25:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:17 INFO - 'USER': 'cltbld', 05:25:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:17 INFO - 'XPC_FLAGS': '0x0', 05:25:17 INFO - 'XPC_SERVICE_NAME': '0', 05:25:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:17 INFO - Downloading/unpacking jsonschema 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:25:20 INFO - Downloading/unpacking functools32 (from jsonschema) 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25:20 INFO - Downloading functools32-3.2.3-2.tar.gz 05:25:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:25:20 INFO - warning: no files found matching '*.txt' 05:25:20 INFO - no previously-included directories found matching 'build' 05:25:20 INFO - no previously-included directories found matching 'dist' 05:25:20 INFO - no previously-included directories found matching '.git*' 05:25:20 INFO - Installing collected packages: jsonschema, functools32 05:25:20 INFO - Running setup.py install for functools32 05:25:20 INFO - warning: no files found matching '*.txt' 05:25:20 INFO - no previously-included directories found matching 'build' 05:25:20 INFO - no previously-included directories found matching 'dist' 05:25:20 INFO - no previously-included directories found matching '.git*' 05:25:20 INFO - Successfully installed jsonschema functools32 05:25:20 INFO - Cleaning up... 05:25:21 INFO - Return code: 0 05:25:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:25:21 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')]} 05:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:21 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')]} 05:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:21 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 0x10c58ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c591570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9070d23df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, '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.rJ59TefqHT/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.uJDubBCaWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 05:25:21 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 05:25:21 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 05:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:21 INFO - 'HOME': '/Users/cltbld', 05:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:21 INFO - 'LOGNAME': 'cltbld', 05:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:21 INFO - 'MOZ_NO_REMOTE': '1', 05:25:21 INFO - 'NO_EM_RESTART': '1', 05:25:21 INFO - 'PAGER': '/bin/cat', 05:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:21 INFO - 'PWD': '/builds/slave/test', 05:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:21 INFO - 'SHELL': '/bin/bash', 05:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:25:21 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:21 INFO - 'USER': 'cltbld', 05:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:21 INFO - 'XPC_FLAGS': '0x0', 05:25:21 INFO - 'XPC_SERVICE_NAME': '0', 05:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:21 INFO - Downloading/unpacking flake8 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25: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 05:25:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:25:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:25:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:25:24 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 05:25:24 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 05:25:24 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 05:25:24 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 05:25:24 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 05:25:24 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 05:25:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:25:24 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 05:25:24 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 05:25:24 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 05:25:24 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 05:25:24 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 05:25:24 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 05:25:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:25:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:25:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:25:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:25:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:25:24 INFO - no previously-included directories found matching 'docs/_build' 05:25:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:25:25 INFO - Running setup.py install for pep8 05:25:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:25:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:25:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:25:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:25:25 INFO - no previously-included directories found matching 'docs/_build' 05:25:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:25:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:25:25 INFO - Cleaning up... 05:25:25 INFO - Return code: 0 05:25:25 INFO - Running post-action listener: _resource_record_post_action 05:25:25 INFO - Running post-action listener: _start_resource_monitoring 05:25:25 INFO - Starting resource monitoring. 05:25:25 INFO - ##### 05:25:25 INFO - ##### Finished create-virtualenv step (success) 05:25:25 INFO - ##### 05:25:25 INFO - ##### 05:25:25 INFO - ##### Running install step. 05:25:25 INFO - ##### 05:25:25 INFO - Running pre-action listener: _resource_record_pre_action 05:25:25 INFO - Running main action method: install 05:25:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:25:25 INFO - Reading from file tmpfile_stdout 05:25:25 INFO - Detecting whether we're running mozinstall >=1.0... 05:25:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:25:25 INFO - Reading from file tmpfile_stdout 05:25:25 INFO - Output received: 05:25:25 INFO - Usage: mozinstall [options] installer 05:25:25 INFO - Options: 05:25:25 INFO - -h, --help show this help message and exit 05:25:25 INFO - -d DEST, --destination=DEST 05:25:25 INFO - Directory to install application into. [default: 05:25:25 INFO - "/builds/slave/test"] 05:25:25 INFO - --app=APP Application being installed. [default: firefox] 05:25:25 INFO - mkdir: /builds/slave/test/build/application 05:25:25 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'] 05:25:25 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 05:25:59 INFO - Reading from file tmpfile_stdout 05:25:59 INFO - Output received: 05:25:59 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:25:59 INFO - Running post-action listener: _resource_record_post_action 05:25:59 INFO - ##### 05:25:59 INFO - ##### Finished install step (success) 05:25:59 INFO - ##### 05:25:59 INFO - ##### 05:25:59 INFO - ##### Running run-tests step. 05:25:59 INFO - ##### 05:25:59 INFO - Running pre-action listener: _resource_record_pre_action 05:25:59 INFO - Running main action method: run_tests 05:25:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:25:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:25:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:25:59 INFO - 2016-05-13 05:25:59.390 screenresolution[2515:24869] starting screenresolution argv=screenresolution get 05:25:59 INFO - 2016-05-13 05:25:59.392 screenresolution[2515:24869] Display 0: 1600x1200x32@60 05:25:59 INFO - 2016-05-13 05:25:59.398 screenresolution[2516:24871] starting screenresolution argv=screenresolution list 05:25:59 INFO - Available Modes on Display 0 05:25:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:25:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:25:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:25:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:25:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:25:59 INFO - Intel Iris: 05:25:59 INFO - Chipset Model: Intel Iris 05:25:59 INFO - Type: GPU 05:25:59 INFO - Bus: Built-In 05:25:59 INFO - VRAM (Dynamic, Max): 1536 MB 05:25:59 INFO - Vendor: Intel (0x8086) 05:25:59 INFO - Device ID: 0x0a2e 05:25:59 INFO - Revision ID: 0x0009 05:25:59 INFO - Displays: 05:25:59 INFO - Display: 05:25:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:25:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:25:59 INFO - Display Serial Number: 1600x1200 60 05:25:59 INFO - Main Display: Yes 05:25:59 INFO - Mirror: Off 05:25:59 INFO - Online: Yes 05:25:59 INFO - Rotation: Supported 05:25:59 INFO - Return code: 0 05:25:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:25:59 INFO - Python 2.7.3 05:25:59 INFO - Return code: 0 05:25:59 INFO - grabbing minidump binary from tooltool 05:25: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')]} 05:25:59 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 0x10c42fd30>, '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 0x10c253db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c426b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:25:59 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 05:25:59 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 05:25:59 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 05:25:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:25:59 INFO - Return code: 0 05:25:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:25:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:25:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:25:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:25:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:25:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:26:00 INFO - Return code: 0 05:26:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0126', '--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 05:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0126 --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 05:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rJ59TefqHT/Render', 05:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:00 INFO - 'HOME': '/Users/cltbld', 05:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:00 INFO - 'LOGNAME': 'cltbld', 05:26:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:26:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:00 INFO - 'MOZ_NO_REMOTE': '1', 05:26:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:26:00 INFO - 'NO_EM_RESTART': '1', 05:26:00 INFO - 'PAGER': '/bin/cat', 05:26:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:00 INFO - 'PWD': '/builds/slave/test', 05:26:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:00 INFO - 'SHELL': '/bin/bash', 05:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uJDubBCaWc/Listeners', 05:26:00 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 05:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:00 INFO - 'USER': 'cltbld', 05:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:00 INFO - 'XPC_FLAGS': '0x0', 05:26:00 INFO - 'XPC_SERVICE_NAME': '0', 05:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0126', '--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 05:26:01 INFO - mozversion application_buildid: 20160513030539 05:26:01 INFO - mozversion application_changeset: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 05:26:01 INFO - mozversion application_display_name: Nightly 05:26:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:26:01 INFO - mozversion application_name: Firefox 05:26:01 INFO - mozversion application_remotingname: firefox 05:26:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:26:01 INFO - mozversion application_vendor: Mozilla 05:26:01 INFO - mozversion application_version: 49.0a1 05:26:01 INFO - mozversion platform_buildid: 20160513030539 05:26:01 INFO - mozversion platform_changeset: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 05:26:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:26:01 INFO - mozversion platform_version: 49.0a1 05:26:01 INFO - using testdate: 1463142361 05:26:01 INFO - actual date: 1463142361 05:26:01 INFO - starting webserver on 'localhost:54729' 05:26:01 INFO - SUITE-START | Running 2 tests 05:26:01 INFO - TEST-START | dromaeo_css 05:26:01 INFO - operating with platform_type : mac_ 05:26:01 INFO - Initialising browser for dromaeo_css test... 05:26:01 INFO - TEST-INFO | started process 2534 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpGO18uN/profile http://localhost:54729/getInfo.html) 05:26:03 INFO - PROCESS | 2534 | __metrics Screen width/height:1600/1200 05:26:03 INFO - PROCESS | 2534 | colorDepth:24 05:26:03 INFO - PROCESS | 2534 | Browser inner width/height: 1024/689 05:26:03 INFO - PROCESS | 2534 | __metrics 05:26:03 INFO - PROCESS | 2534 | 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" 05:26:03 ERROR - PROCESS | 2534 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:26:04 INFO - TEST-INFO | 2534: exit 0 05:26:04 INFO - Browser initialized. 05:26:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:26:04 INFO - TEST-INFO | started process 2537 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpGO18uN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:16 INFO - PROCESS | 2537 | RSS: Main: 239075328 05:26:16 INFO - PROCESS | 2537 | 05:27:07 INFO - PROCESS | 2537 | 1463142427511 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] 05:29:17 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:54729/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54729/tests/dromaeo/cssquery-ext.html) 05:29:19 INFO - PROCESS | 2537 | RSS: Main: 340672512 05:29:19 INFO - PROCESS | 2537 | 05:32:22 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:54729/tests/dromaeo/cssquery-ext.html (next: http://localhost:54729/tests/dromaeo/cssquery-jquery.html) 05:32:22 INFO - PROCESS | 2537 | RSS: Main: 376709120 05:32:22 INFO - PROCESS | 2537 | 05:35:24 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:54729/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54729/tests/dromaeo/cssquery-mootools.html) 05:35:25 INFO - PROCESS | 2537 | RSS: Main: 367988736 05:35:25 INFO - PROCESS | 2537 | 05:38:27 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:54729/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54729/tests/dromaeo/cssquery-prototype.html) 05:38:28 INFO - PROCESS | 2537 | RSS: Main: 368295936 05:38:28 INFO - PROCESS | 2537 | 05:41:31 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:54729/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54729/tests/dromaeo/cssquery-yui.html) 05:41:32 INFO - PROCESS | 2537 | RSS: Main: 336056320 05:41:32 INFO - PROCESS | 2537 | 05:44:35 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:54729/tests/dromaeo/cssquery-yui.html (next: http://localhost:54729/tests/dromaeo/cssquery-dojo.html) 05:44:35 INFO - PROCESS | 2537 | RSS: Main: 376729600 05:44:35 INFO - PROCESS | 2537 | 05:44:35 INFO - PROCESS | 2537 | __start_tp_report 05:44:35 INFO - PROCESS | 2537 | _x_x_mozilla_page_load 05:44:35 INFO - PROCESS | 2537 | _x_x_mozilla_page_load_details 05:44:35 INFO - PROCESS | 2537 | |i|pagename|runs| 05:44:35 INFO - PROCESS | 2537 | |0;dojo.html;2842.346260360117;3359.1770016346;3390.1524618845297;3400.727941764659;3420.110771199489;5329.387120481146;5472.288602481677;5513.972430137844;5573.136163894597;5615.522680572156;4994;5559.638623489481;5611.466910643482;5645.463680950314;5670.914936275959;4940.802367905279;4994.275830004647;5037;5051.368578927634;5171.974140129294;5073.543381095701;5107.897842043157;5113.463086375933;5138.2806407103035;5159.871003224912;4732.124556956939;4986.725730084844;5027.748612569357;5045.64680472367;5075.377133537241;4998.825041123543;5006.098902197603;5016.347874776292;5025.3718285214345;5065.290859279704;5003.924941125886;5028.522290382408;5053.191489361684;5058.519440653132;5082.923756143661;5426.403095659474;5494.725263736797;5500.312460942382;5548.167774833767;5580.358258800249;6155.199824022849;6171.166892469504;6176.4441200292185;6189.350118237565;6199.442050215457;6143.846403839895;6204.9689751551405;6222.719977601018;6229.190205273331;6238.438540531374;5615.989121958046;5664.433556644302;5677.318721753416;5713.685747283897;5733.512651424676;6260.874782504416;6266.780662676875;6267.749290028439;6273.592216505913;6290.685465726695;6083.113344966613;6093.538901692764;6102.509847164099;6106.297775755845;6106.473381654587;6070.726817293144;6085.056816193497;6137;6174.320824709278;6209.223847019122;5980.581359304894;6098.704546243851;6100.1270446244;6110.133516259585;6119.204503414528;6184.783532576338;6196.907046394308;6201.131841542187;6208.441240288395;6210.130581718563;4860.343853579792;4864.343313652683;4865.318855360252;4871.244957031666;4917.0903382874285;4308.569143085729;4320.697551171388;4395.956040439619;4404.207242696281;4432.468103827497;5611.579131565149;5614.326280846243;5616.438356164351;5620.775168993195;5646.576506761927;6168.0439531872635;6169.537284703665;6176.629402235791;6177.907331390033;6212.689365531705;4304.913901721948;5440.401555828856;5601.7759289628775;5602.579806514527;5635.042042852725;5309.495597918191;5363.302770639792;5366.7048312343195;5375.193720941812;5395.298611180521;6303.086052522451;6307.180066591313;6312.621242725451;6321.841953951188;6323.588966717149;6071.3625069368845;6093.564874256955;6125.601746185282;6179.62922224668;6210.254769427517;6297.937020629735;6298.842528936813;6308.71610777507;6323.462505687068;6327.3988971047675;6134.78528251509;6151.938480615316;6162.26052873658;6164.630122192682;6166.81499555014;6093.746126961412;6100.26796784371;6106.8167954959645;6118.497354392036;6138.202033735586;6048.395160483917;6056.666883321283;6061;6077.604955677867;6088.543359247986;4649.744264065509;6045.274567051982;6050.45545900871;6051.939480605138;6066.7269972850445;5198.804275016843;5506.862328441821;5692.0608099663095;5859.853503662443;5870;5895.9115613266695;5926.4666180044005;5994.910076348771;6010.54920880927;6011.549133814894;5732.942670573241;5786.566007549535;5795.913061304168;5798.275215598051;5803.680797556175 05:44:35 INFO - PROCESS | 2537 | |1;ext.html;496.6027178257394;499.5004995004995;499.7556085347486;531.548184043563;540.1708377640323;30587.99999999997;32424.67575324249;33566;33568;33575.83212083936;43403.56596434035;44089.559104408945;44126;46914.530854691446;47139.528604713945;9479.620815167402;9629.27780416468;9648.951755241214;9649.276304277208;9649.469279189641;8626.439281446686;8890;9477.289203309745;9580.137787599115;9598.376105553152;8754.47473151613;11361.772764544705;11591.362475063868;11601.709957251094;11701.356425396641;11563.364014979174;11635.185537012412;11648.951594356464;11649.068074554016;11658.358790266531;8596.398252122353;11237.381943993078;11384.316940983485;11462.598809041643;11468.65594032172;40940;40953.38569921454;40964;41041.15314810841;41045;3173.9206519836957;3263.820489873056;3482.97252310569;3734.439834024891;4007.2586571484367;27809.304767380774;29478;29535.818567257284;29542.85228573865;30109.397812043637;2691.7308269173122;2728.5088684036864;2732.3715545424666;2796.6364372631633;2812.5781132830034;517888;520316;520662.9999999962;520874;521128;55255.72372138114;60043.699781500814;62472.00223982071;64058.67970660117;64098.0385294221;67658;67668.32331676719;67819;67879.66060169667;67882.32117678858;16765.832341676673;17008.744868826976;17021.659566808594;17188.742168867477;17191;12975.351232438341;13042.087053906136;13066.954643628487;13073.607791766262;13090.14914030585;16017.279222435018;16394.1802909854;17240.91379543094;17803.73294400585;18162.273509059753;5319.627626066215;6389.808305750835;6394.848927193057;6445.581037232659;6449.645269510222;974.546835721393;1101.3557069114659;1104.9889501104885;1130.225795330185;1195.8684544700077;29153.854230729135;29368.412631747244;29574.704252957625;30222.54666180009;30487.390252194833;1106.2090605217309;1141.4692168141853;1154.3016475032484;1183.3905538647602;1195.8564972203392;12519;13678.726425471436;15036.097834129987;15335.156566388732;15511;613388;666545;671060;942774;948826;59697.10454343188;60127;60471.69764151151;60525.39474605286;60560;1464.670449148933;1488.7990121333698;1491.3139955620102;1504.458394977807;1504.7667611520233;29376.55935161017;29412.558811617415;29472.852635736683;30279.69720302857;30347.848260758554;51804.740976294874;52481;52656;52869.7356513215;53054.73472632612;526310;527707;528720;528841;528886;23438.414039649146;23589.764102358757;24199.395015124763;26004.86997565;26389;36769.448458272615;42274.577254227064;44188.89527761703;46247;47510.762446187546;37484.43773343347;37949.620503794606;37949.62050379571;38440;38704.6129538701;29625.555616665337;31403.900863470582;33044.66955330416;36523;37176.88469345924;36341.45487817738;36913.07717307089;37165.81417092897;37319.62680373162;37361;31983.400829958875;32476.512852307707;32841;33754.66245337515;34685.13287167841;24984.500309994062;25003.87498062498;25078.24765257045;25722.871385642953;25829.870850645628 05:44:35 INFO - PROCESS | 2537 | |2;jquery.html;391.80409795102446;394.1919065914874;401.8714011516316;404.81783197561106;419.8110850117446;14963.92518037411;20291.28980485683;21292.36122916315;21491.355259342225;21500.817455039945;20529.4867628309;20572.177112915466;21159.576808463822;21179.152833886626;21229.787702122972;5857.209276747627;6095.603785753934;6101.389861013885;6119.785807496739;6156.138140660311;5085;6082.695865206744;6089.573729838913;6125.886569133641;6133.080037994292;5941.079132734433;6013.789517366893;6049;6058.697065146725;6067.423594758513;4992.32603598515;5767.8175973925245;5953.791617293374;5965.850853728648;6024.698765061751;5185.118529849935;5211.7133557654315;5214.0875346814155;5216.843494695166;5223.660462069954;13215.405306761219;13905.79141312881;20265.898670506554;26768.330791730168;26867.865660671767;2018.9596208075757;2078.366098340006;2107.262458139654;2272.0570963050313;2556.9380270086463;9587.712368628883;10134.290599658027;10152.543135558917;10353.534090965924;10464.476776161162;1700.7874015748032;1733.4106403822634;1752.7458518514743;1767.1517671517615;1776.4759395978217;129563.05655415176;132232;133117;133524;134501;11711;11737.706557335963;11766.058715302755;11778.234414763014;11823;13425.530106446227;14181.645458863612;14188.219647919257;15054.096754194783;15068.321925513379;7628.4444711021315;7918.168592297841;8125.309348705427;8303.501789892625;8562.902088988238;8634.136586341316;8652;8656.913430865736;8749.65001399937;8813.603387847434;4643.442786865598;4670.743109128963;4674.041821426599;4690.179218636725;4732.053589282157;1515.3181068519211;1921.4812000759716;1955.82650409753;1957.4812674641232;1983.9107240174226;929.3448119076036;958.7315551645411;970.6602689058773;975.3757595139117;991.4943378876825;19961.700574491395;20415.314215379574;20854.895725521274;21024.053917573747;21095;951.3483263964207;974.4299584742863;981.2640519610293;981.9263555233387;997.3018886779283;10536.473176341153;10977;11005.504752286166;11048.281861678965;11091.057260132979;599199;613017;614001;614762;614770;127623.36188319184;133747;136062;139345;142499;1004.1815920025302;1007.0835539658875;1023.8873723890366;1026.8511065895402;1029.3926583315879;16115.919420402823;18412.815871841645;18542.165602547648;18560;18577.628447431245;45875.77062114668;46283;48287.03425931532;48988;49012;71503;72111;72488;72488.63755681399;74349.25650743423;6824.283450237852;8112.878306825284;8123.593820309079;8201.79495512617;8520.488770673779;10598.417087060287;10640.095591874777;10988.120950324115;12329.198602090835;12561.685957851127;10274.845877311996;10416.479176041015;10608.52261648243;10738.355698658106;11140.442977851237;8472.703455379033;10180.338278011906;10202.28583999113;10519;11014.229003969645;9580.898196707469;11063.336199828036;11219.1024718024;11253.324800511995;11417.543298267976;6971.26801685836;7459.440541959266;8043.3967452442475;8157.4289799715825;8426.988761348677;10264.486775661038;10985.560577576807;11287.209895307578;11306.378149201873;11309.151813613847 05:44:35 INFO - PROCESS | 2537 | |3;mootools.html;1431.527595893355;1438.2305466575385;1608.6300150965278;1867.6171384866104;2054.7328847249846;8146.877796833045;8913.331500137492;9154.771130721727;9171.174594286513;9325;8877;9147.954260228706;9148.313876459275;9157.175854173123;9163.679271225508;2140.090461553841;2196.2752291743686;2210.502636906699;2433.3429973907078;2472.666190064336;2396.7842894139526;2483.639872218527;2489.141246270034;2496.9375765605823;2497.7627125423146;2389.1279682915783;2415.311636183688;2425.8301918865686;2461.237016524883;2470.728219895819;2129.1164166870885;2354.458474550863;2383.0586918167314;2417.286900364397;2426.3570153909227;2202.61454245508;2273.465735552144;2443.8655873926928;2489.9751002489925;2490.86300253486;8347.916520834835;8567.44311619749;9079.68221112258;9156.496392698398;9157.954210228972;4839.153148199086;5209.765560549784;5533.446655334475;5534.833954981316;5554.444555544454;3436.7078798302173;3484.8431820568135;3548.0952357148763;3617.240379520304;3619.040954147153;989.3576662739817;1004.9447280399575;1009.2077718990557;1030.6289735695143;1037.1495373793493;12469.314187719672;12477.75044499114;12482.188657737219;12489.93755031219;12500.499980000699;4569.611583015481;4570.88572785683;4845.466998630148;4862.246351815475;4997.525235102659;10506.422146781848;10555.683329500127;10564.630237941636;10575.788484230427;10577.100946419487;5072.594192464594;5232.947670523322;5608.775648973995;5620.325560932714;5625.409332020079;3738.887833364949;4315.352697095461;4977.502249774993;4983.950160498349;5121.103806833789;3526.682598566141;4128.483939507561;4177.684029530688;4197.8320867165585;4224.936625950613;1323.8146659467682;1328.435414948643;1329.667583104224;1341.7718987772103;1344.3412727763325;827.7599496146171;831.098258389642;900.0999000999001;928.874601928731;932.3753085432776;6047;6102.572819902564;6122.632642041492;6205.441510264098;6309.022101574264;446.23470747667903;460;480.3251431738413;481.02112201669433;484.1068229117273;4001;4069.552349241581;4092.76398927109;4115.485564304462;4147.274227010261;11093.889061109285;11560.94219528897;11564.537418503334;11581.07351411885;11597.13021523389;11332.660020199408;11433.771324573461;11560.959513643813;11567.190296679151;11577.363245021435;8351.958240208882;8612.741617751477;8687.174718401742;8695.173958473935;8715.564221788885;5982.670953097621;6087.178230938944;6110.236220472441;6115.84710382244;6126.816195514141;10296.742581435525;10384.13085644561;10515.526801293807;10671.35971841692;10683.038526532338;8332.416730828783;8391.622376992927;10588.311759735901;10660.307080039773;10766.569337226423;6879.1744990599445;7803.804904877424;7814.101378341564;7943.44395892282;7945.364370850202;8477.491350518989;8558.400911936105;8656.39405241627;8690.217880390795;8702.608382622671;7816.257455541714;7849.058113026245;7931.246531579491;7934.603269836601;7940.6029698513685;7723.7682869514;7737.723275659679;7779.066512018594;7800.92199078002;7910.920890791249;7244.022057022235;7374.188839227573;7398.519096258726;7403.18564957865;7405.888911666437;4957.776900039579;4963.354763880672;5040.84877453677;5118.00198961199;5311.070562651597;5041.218611115283;5107.412647545431;5137.33214682089;5214.608904332114;5382.596305277041 05:44:35 INFO - PROCESS | 2537 | |4;prototype.html;263.6532959158706;303.3145092091872;319.51433820592683;321.62691278117387;325.118927705917;4572.542745725425;4793.712377257359;5090.261912022755;5120.180771076629;5269.366496386118;4384.868453946378;4921.630877684179;4981.302617633534;5092.210707340359;5241.8427447176555;3207.27836236846;4012.638862502374;4027.375756757707;4045.271851066807;4080.2859499587603;3762.924741505168;4031.717779755418;4038.3442788456737;4041.1513582147786;4051.129007263438;3289.3750187464425;3713.108853875064;3799.335116354641;3907.6678482329035;4036.515618125829;3231.1420932963524;3311.9503207451908;3471.895843124698;3914.7651140931634;3983.641472267481;2925.23943774618;2925.517289647206;3183.80897146172;3281.9343613127844;3505.6319086495805;4047.6845025366874;4384.320430333303;4803.927941080887;5007.824726134568;5027.698338099689;4545.2273113570445;4573;4575.5129582885365;4597.908041839178;4616.584507394351;4657.953420465786;4828.830990915336;4929.285253638202;5190.273361729361;5437.613408580826;3203.7597180211346;3443.5867695876655;3876.8643097491736;3880.844766209348;3884.9122245770945;5520.503154716054;5607.52336051432;5690.3456102548735;5706.942930570683;5711.885762284731;3604.9005053458422;3655.6892664123316;3741.7923425178733;3900.473436086149;3916.1580260243613;3704.203596226824;4005.1589166274825;4028.5568587455355;4046.77742724153;4055.53361363447;3867.008490627329;4029.9798501007895;4494.5505449454795;4605.6085232755595;4621.69958952667;4574.604745552639;5468.945310546923;5490.56075513958;5513.428672828225;5513.862153446115;1229.0966139887175;1315.723747964473;1357.4434481862388;1385.3904282115836;1387.3063468265866;858.5149390594295;897.8832751742232;925.5187302602609;937.9343445958854;940.7648087978006;3931.76409415436;4360.934585981149;4756.381670382828;5082.034413461504;5095.286659867622;4905.828296009622;5518.613697041169;5558.610897237155;5632.605717599728;5652.349979752381;4458.554144585581;4819.325294458778;4986.601071914238;5164.251183578435;5248.553872920845;2944.67370777088;2958.9112326630234;2975.3932876247018;2977.672456029835;2988.357503136836;4935.728534930541;5266.289050978069;5373.462653734595;5764.769409223584;5933.893467818948;5230.724634783881;5450.863728406822;5921.897726307099;5943.970280148572;5944.4352786485215;5095.592352611782;5575.944240557624;5942.346341902387;5944.4352786485215;5990.221271234799;4324.437823082979;4811.302361157543;5626.521745651666;5635.746391412315;5730.226419433323;5503.330884521084;5531.502164805026;5548.583856210881;5555.611107222456;5557.694326811902;5800.738966746591;5868.501177399969;5879.647221166743;5917.970410147922;5987.6108052976415;3258.332041931351;3549.9290014199055;3789.2421515697524;3864.719815308981;3875.399313106473;4738.928916066193;5171.767270472763;5596.7333230041895;5633.183188437735;5662.8301150965535;4717.245240761462;5086.415062267886;5109.744512774272;5501.229827824028;5541.806036788693;5053.823116190915;5478.287822583198;5513.807016754395;5538.584606154474;5573.470520300565;4808.43725789113;5044.646874718735;5056.82301119459;5146.8604151466825;5409.459054094559;4531.864044078683;4966.708655749459;5255.894882102413;5456.317960254968;5537.9169312459535;4795.856124316276;5245.396779370421;5438.2658341125025;5440.211169380497;5450.618456707993 05:44:35 INFO - PROCESS | 2537 | |5;yui.html;646.1825790375176;726.1830440754152;741.3253938915586;756.2739769820971;768.9077310722707;14992.17543035134;15548.84451155488;15718.292676829542;15899.205039747996;15957.202139893017;14516.927415362934;14678.412863485475;14706.338214780333;14746.115233086022;14754;736.3005145112138;759.2748924776824;923.1599244687336;939.7415710679584;943.7074506902848;763.6372722956606;937.0442149008007;939.5771902643837;946.7443790176649;950.0309684122191;987.0474991633073;998.880134383875;999.4503023337136;999.7700528878362;1000.8798944126715;766.1227894061269;790.8497385496737;792.8572856885759;990.4948476277115;991.2862738828069;586.3286536915251;586.7594286342579;700.0689083518959;721.206672660075;723.5513981331608;15305.1582162981;15344.07935523861;15367.385304587802;15388.461403850812;15432.537023889301;12467.625971220878;12512.499500019989;12555;12733.61799146027;12765.170263932907;9274.768130796716;10269.435181065039;10612.416317102556;10641.73395665107;11024.228304018721;3880.72834901557;3916.5887581804072;3976.1451287973223;4012.017055821313;4048.817803198863;82364.9999999994;83101.3379732408;84313.46081739782;84340.57829710873;84637;9545;9658.517074146264;9850.753731156778;9965.451900145561;10096.69709908704;18095.72856407155;22211.55624884377;22246.666300005512;22256.777432225794;22300.88849555774;7597.088349397997;7625.847483050308;7640.465167438226;7767.145613982458;7815.570143642154;4033.576474470197;4052.1287923096675;4057.959420405817;4060.4924384451942;4183.309753890574;3314.22115802791;3404.8297585120645;3409.2670075933297;3410.744194185446;3413.8805141819917;588.0502987674874;599.2569214174392;600.6846405637076;603.4237303375354;605.2494906316136;506.94170170430874;569.35947059558;599.3556926304241;601.3728709619963;623.2023010546508;8180.6318715657935;8287.337013038943;8296.958515207385;8415.032271288757;8530.78673033179;505.35062444759046;590.164916642954;599.8050633544116;602.6698173622449;604;3278.8032718036993;3888.125171836314;3899.3566061599518;3939.625735555118;4014.759114453084;87010;89476;89672;95630.52184739032;97175.51412242894;69039;78982.42035159265;85360;86183.5690821542;87040;76396;76795;77170.6141469289;77711;79749;8274.379421543288;8328.500289982621;8408.117147699648;8500.404971652173;8578.18507241811;34564;44048;47015.76492117518;47722.28416573685;49026.509734902196;61885.38114618796;65195.67402163149;67349.98975015477;70022.64988675024;70182;10587.384331429304;10810.51352689115;11004.889951100387;11006.944965275123;11408.258463199865;11719.88280117222;11818.172727908965;11852.70368240801;12184.268943863397;12395.876041239833;11164.385958772345;11333.433328333715;11389.715257118638;11605;11668.88331116678;11064.114870810155;11349.319040857576;11596.94201528987;11625.418729063344;11644;11074;11083.778324433628;11098;11104.722381940515;11105.666829995112;9390.624375024923;9405.58866954664;9413.85879211826;9600.706240607855;9917.65288214909;7986.9965254476665;8789.07714689974;9469.526523673927;9770.951145244228;9820.587331026 05:44:35 INFO - PROCESS | 2537 | __end_tp_report 05:44:35 INFO - PROCESS | 2537 | __start_cc_report 05:44:35 INFO - PROCESS | 2537 | _x_x_mozilla_cycle_collect,1104 05:44:35 INFO - PROCESS | 2537 | __end_cc_report 05:44:35 INFO - PROCESS | 2537 | __startTimestamp1463143475660__endTimestamp 05:44:35 INFO - PROCESS | 2537 | 05:44:35 INFO - PROCESS | 2537 | ------- Summary: start ------- 05:44:35 INFO - PROCESS | 2537 | Number of tests: 6 05:44:35 INFO - PROCESS | 2537 | 05:44:35 INFO - PROCESS | 2537 | [#0] dojo.html Cycles:165 Average:5630.85 Median:5798.28 stddev:672.68 (11.6%) stddev-sans-first:638.18 05:44:35 INFO - PROCESS | 2537 | Values: 2842.3 3359.2 3390.2 3400.7 3420.1 5329.4 5472.3 5514.0 5573.1 5615.5 4994.0 5559.6 5611.5 5645.5 5670.9 4940.8 4994.3 5037.0 5051.4 5172.0 5073.5 5107.9 5113.5 5138.3 5159.9 4732.1 4986.7 5027.7 5045.6 5075.4 4998.8 5006.1 5016.3 5025.4 5065.3 5003.9 5028.5 5053.2 5058.5 5082.9 5426.4 5494.7 5500.3 5548.2 5580.4 6155.2 6171.2 6176.4 6189.4 6199.4 6143.8 6205.0 6222.7 6229.2 6238.4 5616.0 5664.4 5677.3 5713.7 5733.5 6260.9 6266.8 6267.7 6273.6 6290.7 6083.1 6093.5 6102.5 6106.3 6106.5 6070.7 6085.1 6137.0 6174.3 6209.2 5980.6 6098.7 6100.1 6110.1 6119.2 6184.8 6196.9 6201.1 6208.4 6210.1 4860.3 4864.3 4865.3 4871.2 4917.1 4308.6 4320.7 4396.0 4404.2 4432.5 5611.6 5614.3 5616.4 5620.8 5646.6 6168.0 6169.5 6176.6 6177.9 6212.7 4304.9 5440.4 5601.8 5602.6 5635.0 5309.5 5363.3 5366.7 5375.2 5395.3 6303.1 6307.2 6312.6 6321.8 6323.6 6071.4 6093.6 6125.6 6179.6 6210.3 6297.9 6298.8 6308.7 6323.5 6327.4 6134.8 6151.9 6162.3 6164.6 6166.8 6093.7 6100.3 6106.8 6118.5 6138.2 6048.4 6056.7 6061.0 6077.6 6088.5 4649.7 6045.3 6050.5 6051.9 6066.7 5198.8 5506.9 5692.1 5859.9 5870.0 5895.9 5926.5 5994.9 6010.5 6011.5 5732.9 5786.6 5795.9 5798.3 5803.7 05:44:35 INFO - PROCESS | 2537 | 05:44:35 INFO - PROCESS | 2537 | [#1] ext.html Cycles:180 Average:73118.31 Median:34219.90 stddev:167210.83 (488.6%) stddev-sans-first:167591.00 05:44:35 INFO - PROCESS | 2537 | Values: 496.6 499.5 499.8 531.5 540.2 30588.0 32424.7 33566.0 33568.0 33575.8 43403.6 44089.6 44126.0 46914.5 47139.5 9479.6 9629.3 9649.0 9649.3 9649.5 8626.4 8890.0 9477.3 9580.1 9598.4 8754.5 11361.8 11591.4 11601.7 11701.4 11563.4 11635.2 11649.0 11649.1 11658.4 8596.4 11237.4 11384.3 11462.6 11468.7 40940.0 40953.4 40964.0 41041.2 41045.0 3173.9 3263.8 3483.0 3734.4 4007.3 27809.3 29478.0 29535.8 29542.9 30109.4 2691.7 2728.5 2732.4 2796.6 2812.6 517888.0 520316.0 520663.0 520874.0 521128.0 55255.7 60043.7 62472.0 64058.7 64098.0 67658.0 67668.3 67819.0 67879.7 67882.3 16765.8 17008.7 17021.7 17188.7 17191.0 12975.4 13042.1 13067.0 13073.6 13090.1 16017.3 16394.2 17240.9 17803.7 18162.3 5319.6 6389.8 6394.8 6445.6 6449.6 974.5 1101.4 1105.0 1130.2 1195.9 29153.9 29368.4 29574.7 30222.5 30487.4 1106.2 1141.5 1154.3 1183.4 1195.9 12519.0 13678.7 15036.1 15335.2 15511.0 613388.0 666545.0 671060.0 942774.0 948826.0 59697.1 60127.0 60471.7 60525.4 60560.0 1464.7 1488.8 1491.3 1504.5 1504.8 29376.6 29412.6 29472.9 30279.7 30347.8 51804.7 52481.0 52656.0 52869.7 53054.7 526310.0 527707.0 528720.0 528841.0 528886.0 23438.4 23589.8 24199.4 26004.9 26389.0 36769.4 42274.6 44188.9 46247.0 47510.8 37484.4 37949.6 37949.6 38440.0 38704.6 29625.6 31403.9 33044.7 36523.0 37176.9 36341.5 36913.1 37165.8 37319.6 37361.0 31983.4 32476.5 32841.0 33754.7 34685.1 24984.5 25003.9 25078.2 25722.9 25829.9 05:44:35 INFO - PROCESS | 2537 | 05:44:35 INFO - PROCESS | 2537 | [#2] jquery.html Cycles:180 Average:35453.00 Median:21391.86 stddev:102424.18 (478.8%) stddev-sans-first:102677.67 05:44:35 INFO - PROCESS | 2537 | Values: 391.8 394.2 401.9 404.8 419.8 14963.9 20291.3 21292.4 21491.4 21500.8 20529.5 20572.2 21159.6 21179.2 21229.8 5857.2 6095.6 6101.4 6119.8 6156.1 5085.0 6082.7 6089.6 6125.9 6133.1 5941.1 6013.8 6049.0 6058.7 6067.4 4992.3 5767.8 5953.8 5965.9 6024.7 5185.1 5211.7 5214.1 5216.8 5223.7 13215.4 13905.8 20265.9 26768.3 26867.9 2019.0 2078.4 2107.3 2272.1 2556.9 9587.7 10134.3 10152.5 10353.5 10464.5 1700.8 1733.4 1752.7 1767.2 1776.5 129563.1 132232.0 133117.0 133524.0 134501.0 11711.0 11737.7 11766.1 11778.2 11823.0 13425.5 14181.6 14188.2 15054.1 15068.3 7628.4 7918.2 8125.3 8303.5 8562.9 8634.1 8652.0 8656.9 8749.7 8813.6 4643.4 4670.7 4674.0 4690.2 4732.1 1515.3 1921.5 1955.8 1957.5 1983.9 929.3 958.7 970.7 975.4 991.5 19961.7 20415.3 20854.9 21024.1 21095.0 951.3 974.4 981.3 981.9 997.3 10536.5 10977.0 11005.5 11048.3 11091.1 599199.0 613017.0 614001.0 614762.0 614770.0 127623.4 133747.0 136062.0 139345.0 142499.0 1004.2 1007.1 1023.9 1026.9 1029.4 16115.9 18412.8 18542.2 18560.0 18577.6 45875.8 46283.0 48287.0 48988.0 49012.0 71503.0 72111.0 72488.0 72488.6 74349.3 6824.3 8112.9 8123.6 8201.8 8520.5 10598.4 10640.1 10988.1 12329.2 12561.7 10274.8 10416.5 10608.5 10738.4 11140.4 8472.7 10180.3 10202.3 10519.0 11014.2 9580.9 11063.3 11219.1 11253.3 11417.5 6971.3 7459.4 8043.4 8157.4 8427.0 10264.5 10985.6 11287.2 11306.4 11309.2 05:44:35 INFO - PROCESS | 2537 | 05:44:35 INFO - PROCESS | 2537 | [#3] mootools.html Cycles:180 Average:5910.25 Median:2661.63 stddev:3416.68 (128.4%) stddev-sans-first:3409.68 05:44:35 INFO - PROCESS | 2537 | Values: 1431.5 1438.2 1608.6 1867.6 2054.7 8146.9 8913.3 9154.8 9171.2 9325.0 8877.0 9148.0 9148.3 9157.2 9163.7 2140.1 2196.3 2210.5 2433.3 2472.7 2396.8 2483.6 2489.1 2496.9 2497.8 2389.1 2415.3 2425.8 2461.2 2470.7 2129.1 2354.5 2383.1 2417.3 2426.4 2202.6 2273.5 2443.9 2490.0 2490.9 8347.9 8567.4 9079.7 9156.5 9158.0 4839.2 5209.8 5533.4 5534.8 5554.4 3436.7 3484.8 3548.1 3617.2 3619.0 989.4 1004.9 1009.2 1030.6 1037.1 12469.3 12477.8 12482.2 12489.9 12500.5 4569.6 4570.9 4845.5 4862.2 4997.5 10506.4 10555.7 10564.6 10575.8 10577.1 5072.6 5232.9 5608.8 5620.3 5625.4 3738.9 4315.4 4977.5 4984.0 5121.1 3526.7 4128.5 4177.7 4197.8 4224.9 1323.8 1328.4 1329.7 1341.8 1344.3 827.8 831.1 900.1 928.9 932.4 6047.0 6102.6 6122.6 6205.4 6309.0 446.2 460.0 480.3 481.0 484.1 4001.0 4069.6 4092.8 4115.5 4147.3 11093.9 11560.9 11564.5 11581.1 11597.1 11332.7 11433.8 11561.0 11567.2 11577.4 8352.0 8612.7 8687.2 8695.2 8715.6 5982.7 6087.2 6110.2 6115.8 6126.8 10296.7 10384.1 10515.5 10671.4 10683.0 8332.4 8391.6 10588.3 10660.3 10766.6 6879.2 7803.8 7814.1 7943.4 7945.4 8477.5 8558.4 8656.4 8690.2 8702.6 7816.3 7849.1 7931.2 7934.6 7940.6 7723.8 7737.7 7779.1 7800.9 7910.9 7244.0 7374.2 7398.5 7403.2 7405.9 4957.8 4963.4 5040.8 5118.0 5311.1 5041.2 5107.4 5137.3 5214.6 5382.6 05:44:35 INFO - PROCESS | 2537 | 05:44:35 INFO - PROCESS | 2537 | [#4] prototype.html Cycles:180 Average:4400.32 Median:4867.33 stddev:1355.52 (27.8%) stddev-sans-first:1323.29 05:44:35 INFO - PROCESS | 2537 | Values: 263.7 303.3 319.5 321.6 325.1 4572.5 4793.7 5090.3 5120.2 5269.4 4384.9 4921.6 4981.3 5092.2 5241.8 3207.3 4012.6 4027.4 4045.3 4080.3 3762.9 4031.7 4038.3 4041.2 4051.1 3289.4 3713.1 3799.3 3907.7 4036.5 3231.1 3312.0 3471.9 3914.8 3983.6 2925.2 2925.5 3183.8 3281.9 3505.6 4047.7 4384.3 4803.9 5007.8 5027.7 4545.2 4573.0 4575.5 4597.9 4616.6 4658.0 4828.8 4929.3 5190.3 5437.6 3203.8 3443.6 3876.9 3880.8 3884.9 5520.5 5607.5 5690.3 5706.9 5711.9 3604.9 3655.7 3741.8 3900.5 3916.2 3704.2 4005.2 4028.6 4046.8 4055.5 3867.0 4030.0 4494.6 4605.6 4621.7 4574.6 5468.9 5490.6 5513.4 5513.9 1229.1 1315.7 1357.4 1385.4 1387.3 858.5 897.9 925.5 937.9 940.8 3931.8 4360.9 4756.4 5082.0 5095.3 4905.8 5518.6 5558.6 5632.6 5652.3 4458.6 4819.3 4986.6 5164.3 5248.6 2944.7 2958.9 2975.4 2977.7 2988.4 4935.7 5266.3 5373.5 5764.8 5933.9 5230.7 5450.9 5921.9 5944.0 5944.4 5095.6 5575.9 5942.3 5944.4 5990.2 4324.4 4811.3 5626.5 5635.7 5730.2 5503.3 5531.5 5548.6 5555.6 5557.7 5800.7 5868.5 5879.6 5918.0 5987.6 3258.3 3549.9 3789.2 3864.7 3875.4 4738.9 5171.8 5596.7 5633.2 5662.8 4717.2 5086.4 5109.7 5501.2 5541.8 5053.8 5478.3 5513.8 5538.6 5573.5 4808.4 5044.6 5056.8 5146.9 5409.5 4531.9 4966.7 5255.9 5456.3 5537.9 4795.9 5245.4 5438.3 5440.2 5450.6 05:44:35 INFO - PROCESS | 2537 | 05:44:35 INFO - PROCESS | 2537 | [#5] yui.html Cycles:180 Average:18551.20 Median:600.24 stddev:26344.79 (4389.0%) stddev-sans-first:26384.39 05:44:35 INFO - PROCESS | 2537 | Values: 646.2 726.2 741.3 756.3 768.9 14992.2 15548.8 15718.3 15899.2 15957.2 14516.9 14678.4 14706.3 14746.1 14754.0 736.3 759.3 923.2 939.7 943.7 763.6 937.0 939.6 946.7 950.0 987.0 998.9 999.5 999.8 1000.9 766.1 790.8 792.9 990.5 991.3 586.3 586.8 700.1 721.2 723.6 15305.2 15344.1 15367.4 15388.5 15432.5 12467.6 12512.5 12555.0 12733.6 12765.2 9274.8 10269.4 10612.4 10641.7 11024.2 3880.7 3916.6 3976.1 4012.0 4048.8 82365.0 83101.3 84313.5 84340.6 84637.0 9545.0 9658.5 9850.8 9965.5 10096.7 18095.7 22211.6 22246.7 22256.8 22300.9 7597.1 7625.8 7640.5 7767.1 7815.6 4033.6 4052.1 4058.0 4060.5 4183.3 3314.2 3404.8 3409.3 3410.7 3413.9 588.1 599.3 600.7 603.4 605.2 506.9 569.4 599.4 601.4 623.2 8180.6 8287.3 8297.0 8415.0 8530.8 505.4 590.2 599.8 602.7 604.0 3278.8 3888.1 3899.4 3939.6 4014.8 87010.0 89476.0 89672.0 95630.5 97175.5 69039.0 78982.4 85360.0 86183.6 87040.0 76396.0 76795.0 77170.6 77711.0 79749.0 8274.4 8328.5 8408.1 8500.4 8578.2 34564.0 44048.0 47015.8 47722.3 49026.5 61885.4 65195.7 67350.0 70022.6 70182.0 10587.4 10810.5 11004.9 11006.9 11408.3 11719.9 11818.2 11852.7 12184.3 12395.9 11164.4 11333.4 11389.7 11605.0 11668.9 11064.1 11349.3 11596.9 11625.4 11644.0 11074.0 11083.8 11098.0 11104.7 11105.7 9390.6 9405.6 9413.9 9600.7 9917.7 7987.0 8789.1 9469.5 9771.0 9820.6 05:44:35 INFO - PROCESS | 2537 | -------- Summary: end -------- 05:44:35 INFO - PROCESS | 2537 | 05:44:36 INFO - TEST-INFO | 2537: exit 0 05:44:36 INFO - TEST-OK | dromaeo_css | took 1115213ms 05:44:36 INFO - TEST-START | kraken 05:44:36 INFO - operating with platform_type : mac_ 05:44:36 INFO - Initialising browser for kraken test... 05:44:36 INFO - TEST-INFO | started process 2540 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpAoUWLc/profile http://localhost:54729/getInfo.html) 05:44:38 INFO - PROCESS | 2540 | __metrics Screen width/height:1600/1200 05:44:38 INFO - PROCESS | 2540 | colorDepth:24 05:44:38 INFO - PROCESS | 2540 | Browser inner width/height: 1024/689 05:44:38 INFO - PROCESS | 2540 | __metrics 05:44:38 INFO - PROCESS | 2540 | 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" 05:44:38 ERROR - PROCESS | 2540 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:44:39 INFO - TEST-INFO | 2540: exit 0 05:44:39 INFO - Browser initialized. 05:44:39 INFO - Running cycle 1/1 for kraken test... 05:44:39 INFO - TEST-INFO | started process 2543 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpAoUWLc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:44:51 INFO - PROCESS | 2543 | RSS: Main: 239087616 05:44:51 INFO - PROCESS | 2543 | 05:44:53 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54729/tests/kraken/driver.html?testName=audio-beat-detection) 05:44:54 INFO - PROCESS | 2543 | RSS: Main: 287244288 05:44:54 INFO - PROCESS | 2543 | 05:44:57 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54729/tests/kraken/driver.html?testName=audio-dft) 05:44:58 INFO - PROCESS | 2543 | RSS: Main: 295899136 05:44:58 INFO - PROCESS | 2543 | 05:45:01 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54729/tests/kraken/driver.html?testName=audio-fft) 05:45:02 INFO - PROCESS | 2543 | RSS: Main: 383803392 05:45:02 INFO - PROCESS | 2543 | 05:45:04 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54729/tests/kraken/driver.html?testName=audio-oscillator) 05:45:05 INFO - PROCESS | 2543 | RSS: Main: 285126656 05:45:05 INFO - PROCESS | 2543 | 05:45:07 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54729/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:45:08 INFO - PROCESS | 2543 | RSS: Main: 288649216 05:45:08 INFO - PROCESS | 2543 | 05:45:11 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54729/tests/kraken/driver.html?testName=imaging-darkroom) 05:45:11 INFO - PROCESS | 2543 | RSS: Main: 328757248 05:45:11 INFO - PROCESS | 2543 | 05:45:14 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54729/tests/kraken/driver.html?testName=imaging-desaturate) 05:45:15 INFO - PROCESS | 2543 | RSS: Main: 329875456 05:45:15 INFO - PROCESS | 2543 | 05:45:18 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54729/tests/kraken/driver.html?testName=json-parse-financial) 05:45:19 INFO - PROCESS | 2543 | RSS: Main: 331431936 05:45:19 INFO - PROCESS | 2543 | 05:45:20 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54729/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:45:21 INFO - PROCESS | 2543 | RSS: Main: 315834368 05:45:21 INFO - PROCESS | 2543 | 05:45:23 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54729/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:45:24 INFO - PROCESS | 2543 | RSS: Main: 322490368 05:45:24 INFO - PROCESS | 2543 | 05:45:26 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54729/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:45:27 INFO - PROCESS | 2543 | RSS: Main: 324833280 05:45:27 INFO - PROCESS | 2543 | 05:45:30 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54729/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:45:30 INFO - PROCESS | 2543 | RSS: Main: 329289728 05:45:30 INFO - PROCESS | 2543 | 05:45:33 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54729/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:45:34 INFO - PROCESS | 2543 | RSS: Main: 326811648 05:45:34 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | Cycle 1(1): loaded http://localhost:54729/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54729/tests/kraken/driver.html?testName=ai-astar) 05:45:36 INFO - PROCESS | 2543 | RSS: Main: 373874688 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | __start_tp_report 05:45:36 INFO - PROCESS | 2543 | _x_x_mozilla_page_load 05:45:36 INFO - PROCESS | 2543 | _x_x_mozilla_page_load_details 05:45:36 INFO - PROCESS | 2543 | |i|pagename|runs| 05:45:36 INFO - PROCESS | 2543 | |0;ai-astar;112;107;113;113;113;111;112;113;111;112 05:45:36 INFO - PROCESS | 2543 | |1;audio-beat-detection;158;122;142;196;119;141;139;101;192;139 05:45:36 INFO - PROCESS | 2543 | |2;audio-dft;185;186;188;185;187;186;186;185;181;182 05:45:36 INFO - PROCESS | 2543 | |3;audio-fft;75;78;56;127;66;89;48;76;133;81 05:45:36 INFO - PROCESS | 2543 | |4;audio-oscillator;92;99;134;79;83;77;102;82;79;101 05:45:36 INFO - PROCESS | 2543 | |5;imaging-gaussian-blur;89;84;88;84;87;83;87;84;87;84 05:45:36 INFO - PROCESS | 2543 | |6;imaging-darkroom;93;92;94;94;93;93;93;93;92;92 05:45:36 INFO - PROCESS | 2543 | |7;imaging-desaturate;84;86;84;84;81;82;85;84;109;133 05:45:36 INFO - PROCESS | 2543 | |8;json-parse-financial;55;55;58;58;57;58;57;116;56;55 05:45:36 INFO - PROCESS | 2543 | |9;json-stringify-tinderbox;64;64;78;73;71;69;68;69;69;69 05:45:36 INFO - PROCESS | 2543 | |10;stanford-crypto-aes;84;86;85;84;83;97;102;85;85;87 05:45:36 INFO - PROCESS | 2543 | |11;stanford-crypto-ccm;151;128;142;147;147;127;140;139;241;141 05:45:36 INFO - PROCESS | 2543 | |12;stanford-crypto-pbkdf2;164;163;164;166;164;165;164;165;163;162 05:45:36 INFO - PROCESS | 2543 | |13;stanford-crypto-sha256-iterative;66;66;66;66;66;66;68;77;82;83 05:45:36 INFO - PROCESS | 2543 | __end_tp_report 05:45:36 INFO - PROCESS | 2543 | __start_cc_report 05:45:36 INFO - PROCESS | 2543 | _x_x_mozilla_cycle_collect,2295 05:45:36 INFO - PROCESS | 2543 | __end_cc_report 05:45:36 INFO - PROCESS | 2543 | __startTimestamp1463143536292__endTimestamp 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | ------- Summary: start ------- 05:45:36 INFO - PROCESS | 2543 | Number of tests: 14 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.50 stddev:1.83 (1.6%) stddev-sans-first:1.94 05:45:36 INFO - PROCESS | 2543 | Values: 112.0 107.0 113.0 113.0 113.0 111.0 112.0 113.0 111.0 112.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:141.50 stddev:30.22 (21.4%) stddev-sans-first:31.67 05:45:36 INFO - PROCESS | 2543 | Values: 158.0 122.0 142.0 196.0 119.0 141.0 139.0 101.0 192.0 139.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#2] audio-dft Cycles:10 Average:185.10 Median:186.00 stddev:2.13 (1.1%) stddev-sans-first:2.26 05:45:36 INFO - PROCESS | 2543 | Values: 185.0 186.0 188.0 185.0 187.0 186.0 186.0 185.0 181.0 182.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.50 stddev:27.60 (36.6%) stddev-sans-first:29.13 05:45:36 INFO - PROCESS | 2543 | Values: 75.0 78.0 56.0 127.0 66.0 89.0 48.0 76.0 133.0 81.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#4] audio-oscillator Cycles:10 Average:92.80 Median:80.50 stddev:17.42 (21.6%) stddev-sans-first:18.48 05:45:36 INFO - PROCESS | 2543 | Values: 92.0 99.0 134.0 79.0 83.0 77.0 102.0 82.0 79.0 101.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:2.11 (2.4%) stddev-sans-first:1.87 05:45:36 INFO - PROCESS | 2543 | Values: 89.0 84.0 88.0 84.0 87.0 83.0 87.0 84.0 87.0 84.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 05:45:36 INFO - PROCESS | 2543 | Values: 93.0 92.0 94.0 94.0 93.0 93.0 93.0 93.0 92.0 92.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#7] imaging-desaturate Cycles:10 Average:91.20 Median:84.00 stddev:16.75 (19.9%) stddev-sans-first:17.56 05:45:36 INFO - PROCESS | 2543 | Values: 84.0 86.0 84.0 84.0 81.0 82.0 85.0 84.0 109.0 133.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:57.00 stddev:18.84 (33.1%) stddev-sans-first:19.79 05:45:36 INFO - PROCESS | 2543 | Values: 55.0 55.0 58.0 58.0 57.0 58.0 57.0 116.0 56.0 55.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.00 stddev:4.09 (5.9%) stddev-sans-first:3.84 05:45:36 INFO - PROCESS | 2543 | Values: 64.0 64.0 78.0 73.0 71.0 69.0 68.0 69.0 69.0 69.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:85.00 stddev:6.37 (7.5%) stddev-sans-first:6.61 05:45:36 INFO - PROCESS | 2543 | Values: 84.0 86.0 85.0 84.0 83.0 97.0 102.0 85.0 85.0 87.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:144.50 stddev:32.79 (22.7%) stddev-sans-first:34.78 05:45:36 INFO - PROCESS | 2543 | Values: 151.0 128.0 142.0 147.0 147.0 127.0 140.0 139.0 241.0 141.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.00 Median:164.00 stddev:1.15 (0.7%) stddev-sans-first:1.22 05:45:36 INFO - PROCESS | 2543 | Values: 164.0 163.0 164.0 166.0 164.0 165.0 164.0 165.0 163.0 162.0 05:45:36 INFO - PROCESS | 2543 | 05:45:36 INFO - PROCESS | 2543 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:67.00 stddev:7.14 (10.7%) stddev-sans-first:7.37 05:45:36 INFO - PROCESS | 2543 | Values: 66.0 66.0 66.0 66.0 66.0 66.0 68.0 77.0 82.0 83.0 05:45:36 INFO - PROCESS | 2543 | -------- Summary: end -------- 05:45:36 INFO - PROCESS | 2543 | 05:45:37 INFO - TEST-INFO | 2543: exit 0 05:45:37 INFO - TEST-OK | kraken | took 61077ms 05:45:37 INFO - SUITE-END | took 1176s 05:45:37 INFO - Completed test suite (00:19:36) 05:45:37 INFO - javascript benchmark 05:45:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2842.346260360117, 3359.1770016346, 3390.1524618845297, 3400.727941764659, 3420.110771199489, 5329.387120481146, 5472.288602481677, 5513.972430137844, 5573.136163894597, 5615.522680572156, 4994.0, 5559.638623489481, 5611.466910643482, 5645.463680950314, 5670.914936275959, 4940.802367905279, 4994.275830004647, 5037.0, 5051.368578927634, 5171.974140129294, 5073.543381095701, 5107.897842043157, 5113.463086375933, 5138.2806407103035, 5159.871003224912, 4732.124556956939, 4986.725730084844, 5027.748612569357, 5045.64680472367, 5075.377133537241, 4998.825041123543, 5006.098902197603, 5016.347874776292, 5025.3718285214345, 5065.290859279704, 5003.924941125886, 5028.522290382408, 5053.191489361684, 5058.519440653132, 5082.923756143661, 5426.403095659474, 5494.725263736797, 5500.312460942382, 5548.167774833767, 5580.358258800249, 6155.199824022849, 6171.166892469504, 6176.4441200292185, 6189.350118237565, 6199.442050215457, 6143.846403839895, 6204.9689751551405, 6222.719977601018, 6229.190205273331, 6238.438540531374, 5615.989121958046, 5664.433556644302, 5677.318721753416, 5713.685747283897, 5733.512651424676, 6260.874782504416, 6266.780662676875, 6267.749290028439, 6273.592216505913, 6290.685465726695, 6083.113344966613, 6093.538901692764, 6102.509847164099, 6106.297775755845, 6106.473381654587, 6070.726817293144, 6085.056816193497, 6137.0, 6174.320824709278, 6209.223847019122, 5980.581359304894, 6098.704546243851, 6100.1270446244, 6110.133516259585, 6119.204503414528, 6184.783532576338, 6196.907046394308, 6201.131841542187, 6208.441240288395, 6210.130581718563, 4860.343853579792, 4864.343313652683, 4865.318855360252, 4871.244957031666, 4917.0903382874285, 4308.569143085729, 4320.697551171388, 4395.956040439619, 4404.207242696281, 4432.468103827497, 5611.579131565149, 5614.326280846243, 5616.438356164351, 5620.775168993195, 5646.576506761927, 6168.0439531872635, 6169.537284703665, 6176.629402235791, 6177.907331390033, 6212.689365531705, 4304.913901721948, 5440.401555828856, 5601.7759289628775, 5602.579806514527, 5635.042042852725, 5309.495597918191, 5363.302770639792, 5366.7048312343195, 5375.193720941812, 5395.298611180521, 6303.086052522451, 6307.180066591313, 6312.621242725451, 6321.841953951188, 6323.588966717149, 6071.3625069368845, 6093.564874256955, 6125.601746185282, 6179.62922224668, 6210.254769427517, 6297.937020629735, 6298.842528936813, 6308.71610777507, 6323.462505687068, 6327.3988971047675, 6134.78528251509, 6151.938480615316, 6162.26052873658, 6164.630122192682, 6166.81499555014, 6093.746126961412, 6100.26796784371, 6106.8167954959645, 6118.497354392036, 6138.202033735586, 6048.395160483917, 6056.666883321283, 6061.0, 6077.604955677867, 6088.543359247986, 4649.744264065509, 6045.274567051982, 6050.45545900871, 6051.939480605138, 6066.7269972850445, 5198.804275016843, 5506.862328441821, 5692.0608099663095, 5859.853503662443, 5870.0, 5895.9115613266695, 5926.4666180044005, 5994.910076348771, 6010.54920880927, 6011.549133814894, 5732.942670573241, 5786.566007549535, 5795.913061304168, 5798.275215598051, 5803.680797556175], "value": 5586.611683751201, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.6027178257394, 499.5004995004995, 499.7556085347486, 531.548184043563, 540.1708377640323, 30587.99999999997, 32424.67575324249, 33566.0, 33568.0, 33575.83212083936, 43403.56596434035, 44089.559104408945, 44126.0, 46914.530854691446, 47139.528604713945, 9479.620815167402, 9629.27780416468, 9648.951755241214, 9649.276304277208, 9649.469279189641, 8626.439281446686, 8890.0, 9477.289203309745, 9580.137787599115, 9598.376105553152, 8754.47473151613, 11361.772764544705, 11591.362475063868, 11601.709957251094, 11701.356425396641, 11563.364014979174, 11635.185537012412, 11648.951594356464, 11649.068074554016, 11658.358790266531, 8596.398252122353, 11237.381943993078, 11384.316940983485, 11462.598809041643, 11468.65594032172, 40940.0, 40953.38569921454, 40964.0, 41041.15314810841, 41045.0, 3173.9206519836957, 3263.820489873056, 3482.97252310569, 3734.439834024891, 4007.2586571484367, 27809.304767380774, 29478.0, 29535.818567257284, 29542.85228573865, 30109.397812043637, 2691.7308269173122, 2728.5088684036864, 2732.3715545424666, 2796.6364372631633, 2812.5781132830034, 517888.0, 520316.0, 520662.9999999962, 520874.0, 521128.0, 55255.72372138114, 60043.699781500814, 62472.00223982071, 64058.67970660117, 64098.0385294221, 67658.0, 67668.32331676719, 67819.0, 67879.66060169667, 67882.32117678858, 16765.832341676673, 17008.744868826976, 17021.659566808594, 17188.742168867477, 17191.0, 12975.351232438341, 13042.087053906136, 13066.954643628487, 13073.607791766262, 13090.14914030585, 16017.279222435018, 16394.1802909854, 17240.91379543094, 17803.73294400585, 18162.273509059753, 5319.627626066215, 6389.808305750835, 6394.848927193057, 6445.581037232659, 6449.645269510222, 974.546835721393, 1101.3557069114659, 1104.9889501104885, 1130.225795330185, 1195.8684544700077, 29153.854230729135, 29368.412631747244, 29574.704252957625, 30222.54666180009, 30487.390252194833, 1106.2090605217309, 1141.4692168141853, 1154.3016475032484, 1183.3905538647602, 1195.8564972203392, 12519.0, 13678.726425471436, 15036.097834129987, 15335.156566388732, 15511.0, 613388.0, 666545.0, 671060.0, 942774.0, 948826.0, 59697.10454343188, 60127.0, 60471.69764151151, 60525.39474605286, 60560.0, 1464.670449148933, 1488.7990121333698, 1491.3139955620102, 1504.458394977807, 1504.7667611520233, 29376.55935161017, 29412.558811617415, 29472.852635736683, 30279.69720302857, 30347.848260758554, 51804.740976294874, 52481.0, 52656.0, 52869.7356513215, 53054.73472632612, 526310.0, 527707.0, 528720.0, 528841.0, 528886.0, 23438.414039649146, 23589.764102358757, 24199.395015124763, 26004.86997565, 26389.0, 36769.448458272615, 42274.577254227064, 44188.89527761703, 46247.0, 47510.762446187546, 37484.43773343347, 37949.620503794606, 37949.62050379571, 38440.0, 38704.6129538701, 29625.555616665337, 31403.900863470582, 33044.66955330416, 36523.0, 37176.88469345924, 36341.45487817738, 36913.07717307089, 37165.81417092897, 37319.62680373162, 37361.0, 31983.400829958875, 32476.512852307707, 32841.0, 33754.66245337515, 34685.13287167841, 24984.500309994062, 25003.87498062498, 25078.24765257045, 25722.871385642953, 25829.870850645628], "value": 20042.21293089552, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.80409795102446, 394.1919065914874, 401.8714011516316, 404.81783197561106, 419.8110850117446, 14963.92518037411, 20291.28980485683, 21292.36122916315, 21491.355259342225, 21500.817455039945, 20529.4867628309, 20572.177112915466, 21159.576808463822, 21179.152833886626, 21229.787702122972, 5857.209276747627, 6095.603785753934, 6101.389861013885, 6119.785807496739, 6156.138140660311, 5085.0, 6082.695865206744, 6089.573729838913, 6125.886569133641, 6133.080037994292, 5941.079132734433, 6013.789517366893, 6049.0, 6058.697065146725, 6067.423594758513, 4992.32603598515, 5767.8175973925245, 5953.791617293374, 5965.850853728648, 6024.698765061751, 5185.118529849935, 5211.7133557654315, 5214.0875346814155, 5216.843494695166, 5223.660462069954, 13215.405306761219, 13905.79141312881, 20265.898670506554, 26768.330791730168, 26867.865660671767, 2018.9596208075757, 2078.366098340006, 2107.262458139654, 2272.0570963050313, 2556.9380270086463, 9587.712368628883, 10134.290599658027, 10152.543135558917, 10353.534090965924, 10464.476776161162, 1700.7874015748032, 1733.4106403822634, 1752.7458518514743, 1767.1517671517615, 1776.4759395978217, 129563.05655415176, 132232.0, 133117.0, 133524.0, 134501.0, 11711.0, 11737.706557335963, 11766.058715302755, 11778.234414763014, 11823.0, 13425.530106446227, 14181.645458863612, 14188.219647919257, 15054.096754194783, 15068.321925513379, 7628.4444711021315, 7918.168592297841, 8125.309348705427, 8303.501789892625, 8562.902088988238, 8634.136586341316, 8652.0, 8656.913430865736, 8749.65001399937, 8813.603387847434, 4643.442786865598, 4670.743109128963, 4674.041821426599, 4690.179218636725, 4732.053589282157, 1515.3181068519211, 1921.4812000759716, 1955.82650409753, 1957.4812674641232, 1983.9107240174226, 929.3448119076036, 958.7315551645411, 970.6602689058773, 975.3757595139117, 991.4943378876825, 19961.700574491395, 20415.314215379574, 20854.895725521274, 21024.053917573747, 21095.0, 951.3483263964207, 974.4299584742863, 981.2640519610293, 981.9263555233387, 997.3018886779283, 10536.473176341153, 10977.0, 11005.504752286166, 11048.281861678965, 11091.057260132979, 599199.0, 613017.0, 614001.0, 614762.0, 614770.0, 127623.36188319184, 133747.0, 136062.0, 139345.0, 142499.0, 1004.1815920025302, 1007.0835539658875, 1023.8873723890366, 1026.8511065895402, 1029.3926583315879, 16115.919420402823, 18412.815871841645, 18542.165602547648, 18560.0, 18577.628447431245, 45875.77062114668, 46283.0, 48287.03425931532, 48988.0, 49012.0, 71503.0, 72111.0, 72488.0, 72488.63755681399, 74349.25650743423, 6824.283450237852, 8112.878306825284, 8123.593820309079, 8201.79495512617, 8520.488770673779, 10598.417087060287, 10640.095591874777, 10988.120950324115, 12329.198602090835, 12561.685957851127, 10274.845877311996, 10416.479176041015, 10608.52261648243, 10738.355698658106, 11140.442977851237, 8472.703455379033, 10180.338278011906, 10202.28583999113, 10519.0, 11014.229003969645, 9580.898196707469, 11063.336199828036, 11219.1024718024, 11253.324800511995, 11417.543298267976, 6971.26801685836, 7459.440541959266, 8043.3967452442475, 8157.4289799715825, 8426.988761348677, 10264.486775661038, 10985.560577576807, 11287.209895307578, 11306.378149201873, 11309.151813613847], "value": 9433.499025115845, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.527595893355, 1438.2305466575385, 1608.6300150965278, 1867.6171384866104, 2054.7328847249846, 8146.877796833045, 8913.331500137492, 9154.771130721727, 9171.174594286513, 9325.0, 8877.0, 9147.954260228706, 9148.313876459275, 9157.175854173123, 9163.679271225508, 2140.090461553841, 2196.2752291743686, 2210.502636906699, 2433.3429973907078, 2472.666190064336, 2396.7842894139526, 2483.639872218527, 2489.141246270034, 2496.9375765605823, 2497.7627125423146, 2389.1279682915783, 2415.311636183688, 2425.8301918865686, 2461.237016524883, 2470.728219895819, 2129.1164166870885, 2354.458474550863, 2383.0586918167314, 2417.286900364397, 2426.3570153909227, 2202.61454245508, 2273.465735552144, 2443.8655873926928, 2489.9751002489925, 2490.86300253486, 8347.916520834835, 8567.44311619749, 9079.68221112258, 9156.496392698398, 9157.954210228972, 4839.153148199086, 5209.765560549784, 5533.446655334475, 5534.833954981316, 5554.444555544454, 3436.7078798302173, 3484.8431820568135, 3548.0952357148763, 3617.240379520304, 3619.040954147153, 989.3576662739817, 1004.9447280399575, 1009.2077718990557, 1030.6289735695143, 1037.1495373793493, 12469.314187719672, 12477.75044499114, 12482.188657737219, 12489.93755031219, 12500.499980000699, 4569.611583015481, 4570.88572785683, 4845.466998630148, 4862.246351815475, 4997.525235102659, 10506.422146781848, 10555.683329500127, 10564.630237941636, 10575.788484230427, 10577.100946419487, 5072.594192464594, 5232.947670523322, 5608.775648973995, 5620.325560932714, 5625.409332020079, 3738.887833364949, 4315.352697095461, 4977.502249774993, 4983.950160498349, 5121.103806833789, 3526.682598566141, 4128.483939507561, 4177.684029530688, 4197.8320867165585, 4224.936625950613, 1323.8146659467682, 1328.435414948643, 1329.667583104224, 1341.7718987772103, 1344.3412727763325, 827.7599496146171, 831.098258389642, 900.0999000999001, 928.874601928731, 932.3753085432776, 6047.0, 6102.572819902564, 6122.632642041492, 6205.441510264098, 6309.022101574264, 446.23470747667903, 460.0, 480.3251431738413, 481.02112201669433, 484.1068229117273, 4001.0, 4069.552349241581, 4092.76398927109, 4115.485564304462, 4147.274227010261, 11093.889061109285, 11560.94219528897, 11564.537418503334, 11581.07351411885, 11597.13021523389, 11332.660020199408, 11433.771324573461, 11560.959513643813, 11567.190296679151, 11577.363245021435, 8351.958240208882, 8612.741617751477, 8687.174718401742, 8695.173958473935, 8715.564221788885, 5982.670953097621, 6087.178230938944, 6110.236220472441, 6115.84710382244, 6126.816195514141, 10296.742581435525, 10384.13085644561, 10515.526801293807, 10671.35971841692, 10683.038526532338, 8332.416730828783, 8391.622376992927, 10588.311759735901, 10660.307080039773, 10766.569337226423, 6879.1744990599445, 7803.804904877424, 7814.101378341564, 7943.44395892282, 7945.364370850202, 8477.491350518989, 8558.400911936105, 8656.39405241627, 8690.217880390795, 8702.608382622671, 7816.257455541714, 7849.058113026245, 7931.246531579491, 7934.603269836601, 7940.6029698513685, 7723.7682869514, 7737.723275659679, 7779.066512018594, 7800.92199078002, 7910.920890791249, 7244.022057022235, 7374.188839227573, 7398.519096258726, 7403.18564957865, 7405.888911666437, 4957.776900039579, 4963.354763880672, 5040.84877453677, 5118.00198961199, 5311.070562651597, 5041.218611115283, 5107.412647545431, 5137.33214682089, 5214.608904332114, 5382.596305277041], "value": 4626.790621121514, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.6532959158706, 303.3145092091872, 319.51433820592683, 321.62691278117387, 325.118927705917, 4572.542745725425, 4793.712377257359, 5090.261912022755, 5120.180771076629, 5269.366496386118, 4384.868453946378, 4921.630877684179, 4981.302617633534, 5092.210707340359, 5241.8427447176555, 3207.27836236846, 4012.638862502374, 4027.375756757707, 4045.271851066807, 4080.2859499587603, 3762.924741505168, 4031.717779755418, 4038.3442788456737, 4041.1513582147786, 4051.129007263438, 3289.3750187464425, 3713.108853875064, 3799.335116354641, 3907.6678482329035, 4036.515618125829, 3231.1420932963524, 3311.9503207451908, 3471.895843124698, 3914.7651140931634, 3983.641472267481, 2925.23943774618, 2925.517289647206, 3183.80897146172, 3281.9343613127844, 3505.6319086495805, 4047.6845025366874, 4384.320430333303, 4803.927941080887, 5007.824726134568, 5027.698338099689, 4545.2273113570445, 4573.0, 4575.5129582885365, 4597.908041839178, 4616.584507394351, 4657.953420465786, 4828.830990915336, 4929.285253638202, 5190.273361729361, 5437.613408580826, 3203.7597180211346, 3443.5867695876655, 3876.8643097491736, 3880.844766209348, 3884.9122245770945, 5520.503154716054, 5607.52336051432, 5690.3456102548735, 5706.942930570683, 5711.885762284731, 3604.9005053458422, 3655.6892664123316, 3741.7923425178733, 3900.473436086149, 3916.1580260243613, 3704.203596226824, 4005.1589166274825, 4028.5568587455355, 4046.77742724153, 4055.53361363447, 3867.008490627329, 4029.9798501007895, 4494.5505449454795, 4605.6085232755595, 4621.69958952667, 4574.604745552639, 5468.945310546923, 5490.56075513958, 5513.428672828225, 5513.862153446115, 1229.0966139887175, 1315.723747964473, 1357.4434481862388, 1385.3904282115836, 1387.3063468265866, 858.5149390594295, 897.8832751742232, 925.5187302602609, 937.9343445958854, 940.7648087978006, 3931.76409415436, 4360.934585981149, 4756.381670382828, 5082.034413461504, 5095.286659867622, 4905.828296009622, 5518.613697041169, 5558.610897237155, 5632.605717599728, 5652.349979752381, 4458.554144585581, 4819.325294458778, 4986.601071914238, 5164.251183578435, 5248.553872920845, 2944.67370777088, 2958.9112326630234, 2975.3932876247018, 2977.672456029835, 2988.357503136836, 4935.728534930541, 5266.289050978069, 5373.462653734595, 5764.769409223584, 5933.893467818948, 5230.724634783881, 5450.863728406822, 5921.897726307099, 5943.970280148572, 5944.4352786485215, 5095.592352611782, 5575.944240557624, 5942.346341902387, 5944.4352786485215, 5990.221271234799, 4324.437823082979, 4811.302361157543, 5626.521745651666, 5635.746391412315, 5730.226419433323, 5503.330884521084, 5531.502164805026, 5548.583856210881, 5555.611107222456, 5557.694326811902, 5800.738966746591, 5868.501177399969, 5879.647221166743, 5917.970410147922, 5987.6108052976415, 3258.332041931351, 3549.9290014199055, 3789.2421515697524, 3864.719815308981, 3875.399313106473, 4738.928916066193, 5171.767270472763, 5596.7333230041895, 5633.183188437735, 5662.8301150965535, 4717.245240761462, 5086.415062267886, 5109.744512774272, 5501.229827824028, 5541.806036788693, 5053.823116190915, 5478.287822583198, 5513.807016754395, 5538.584606154474, 5573.470520300565, 4808.43725789113, 5044.646874718735, 5056.82301119459, 5146.8604151466825, 5409.459054094559, 4531.864044078683, 4966.708655749459, 5255.894882102413, 5456.317960254968, 5537.9169312459535, 4795.856124316276, 5245.396779370421, 5438.2658341125025, 5440.211169380497, 5450.618456707993], "value": 3979.4580480769205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.1825790375176, 726.1830440754152, 741.3253938915586, 756.2739769820971, 768.9077310722707, 14992.17543035134, 15548.84451155488, 15718.292676829542, 15899.205039747996, 15957.202139893017, 14516.927415362934, 14678.412863485475, 14706.338214780333, 14746.115233086022, 14754.0, 736.3005145112138, 759.2748924776824, 923.1599244687336, 939.7415710679584, 943.7074506902848, 763.6372722956606, 937.0442149008007, 939.5771902643837, 946.7443790176649, 950.0309684122191, 987.0474991633073, 998.880134383875, 999.4503023337136, 999.7700528878362, 1000.8798944126715, 766.1227894061269, 790.8497385496737, 792.8572856885759, 990.4948476277115, 991.2862738828069, 586.3286536915251, 586.7594286342579, 700.0689083518959, 721.206672660075, 723.5513981331608, 15305.1582162981, 15344.07935523861, 15367.385304587802, 15388.461403850812, 15432.537023889301, 12467.625971220878, 12512.499500019989, 12555.0, 12733.61799146027, 12765.170263932907, 9274.768130796716, 10269.435181065039, 10612.416317102556, 10641.73395665107, 11024.228304018721, 3880.72834901557, 3916.5887581804072, 3976.1451287973223, 4012.017055821313, 4048.817803198863, 82364.9999999994, 83101.3379732408, 84313.46081739782, 84340.57829710873, 84637.0, 9545.0, 9658.517074146264, 9850.753731156778, 9965.451900145561, 10096.69709908704, 18095.72856407155, 22211.55624884377, 22246.666300005512, 22256.777432225794, 22300.88849555774, 7597.088349397997, 7625.847483050308, 7640.465167438226, 7767.145613982458, 7815.570143642154, 4033.576474470197, 4052.1287923096675, 4057.959420405817, 4060.4924384451942, 4183.309753890574, 3314.22115802791, 3404.8297585120645, 3409.2670075933297, 3410.744194185446, 3413.8805141819917, 588.0502987674874, 599.2569214174392, 600.6846405637076, 603.4237303375354, 605.2494906316136, 506.94170170430874, 569.35947059558, 599.3556926304241, 601.3728709619963, 623.2023010546508, 8180.6318715657935, 8287.337013038943, 8296.958515207385, 8415.032271288757, 8530.78673033179, 505.35062444759046, 590.164916642954, 599.8050633544116, 602.6698173622449, 604.0, 3278.8032718036993, 3888.125171836314, 3899.3566061599518, 3939.625735555118, 4014.759114453084, 87010.0, 89476.0, 89672.0, 95630.52184739032, 97175.51412242894, 69039.0, 78982.42035159265, 85360.0, 86183.5690821542, 87040.0, 76396.0, 76795.0, 77170.6141469289, 77711.0, 79749.0, 8274.379421543288, 8328.500289982621, 8408.117147699648, 8500.404971652173, 8578.18507241811, 34564.0, 44048.0, 47015.76492117518, 47722.28416573685, 49026.509734902196, 61885.38114618796, 65195.67402163149, 67349.98975015477, 70022.64988675024, 70182.0, 10587.384331429304, 10810.51352689115, 11004.889951100387, 11006.944965275123, 11408.258463199865, 11719.88280117222, 11818.172727908965, 11852.70368240801, 12184.268943863397, 12395.876041239833, 11164.385958772345, 11333.433328333715, 11389.715257118638, 11605.0, 11668.88331116678, 11064.114870810155, 11349.319040857576, 11596.94201528987, 11625.418729063344, 11644.0, 11074.0, 11083.778324433628, 11098.0, 11104.722381940515, 11105.666829995112, 9390.624375024923, 9405.58866954664, 9413.85879211826, 9600.706240607855, 9917.65288214909, 7986.9965254476665, 8789.07714689974, 9469.526523673927, 9770.951145244228, 9820.587331026], "value": 6919.521963617473, "unit": "score"}], "name": "dromaeo_css", "value": 7158.66992319553, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 113.0, 113.0, 113.0, 111.0, 112.0, 113.0, 111.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 142.0, 196.0, 119.0, 141.0, 139.0, 101.0, 192.0, 139.0], "value": 144.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 188.0, 185.0, 187.0, 186.0, 186.0, 185.0, 181.0, 182.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 56.0, 127.0, 66.0, 89.0, 48.0, 76.0, 133.0, 81.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 99.0, 134.0, 79.0, 83.0, 77.0, 102.0, 82.0, 79.0, 101.0], "value": 92.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 88.0, 84.0, 87.0, 83.0, 87.0, 84.0, 87.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 94.0, 93.0, 93.0, 93.0, 93.0, 92.0, 92.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 84.0, 81.0, 82.0, 85.0, 84.0, 109.0, 133.0], "value": 91.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 58.0, 58.0, 57.0, 58.0, 57.0, 116.0, 56.0, 55.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 78.0, 73.0, 71.0, 69.0, 68.0, 69.0, 69.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 84.0, 83.0, 97.0, 102.0, 85.0, 85.0, 87.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 128.0, 142.0, 147.0, 147.0, 127.0, 140.0, 139.0, 241.0, 141.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 166.0, 164.0, 165.0, 164.0, 165.0, 163.0, 162.0], "value": 164.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 68.0, 77.0, 82.0, 83.0], "value": 70.6, "unit": "score"}], "name": "kraken", "value": 1491.8, "alertThreshold": 2.0}]} 05:45:37 INFO - Return code: 0 05:45:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:45:37 ERROR - # TBPL SUCCESS # 05:45:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:45:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:45:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:45:37 INFO - 2016-05-13 05:45:37.594 screenresolution[2547:26751] starting screenresolution argv=screenresolution get 05:45:37 INFO - 2016-05-13 05:45:37.596 screenresolution[2547:26751] Display 0: 1600x1200x32@60 05:45:37 INFO - 2016-05-13 05:45:37.603 screenresolution[2548:26754] starting screenresolution argv=screenresolution list 05:45:37 INFO - Available Modes on Display 0 05:45:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:45:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:45:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:45:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:45:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:45:37 INFO - Intel Iris: 05:45:37 INFO - Chipset Model: Intel Iris 05:45:37 INFO - Type: GPU 05:45:37 INFO - Bus: Built-In 05:45:37 INFO - VRAM (Dynamic, Max): 1536 MB 05:45:37 INFO - Vendor: Intel (0x8086) 05:45:37 INFO - Device ID: 0x0a2e 05:45:37 INFO - Revision ID: 0x0009 05:45:37 INFO - Displays: 05:45:37 INFO - Display: 05:45:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:45:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:45:37 INFO - Display Serial Number: 1600x1200 60 05:45:37 INFO - Main Display: Yes 05:45:37 INFO - Mirror: Off 05:45:37 INFO - Online: Yes 05:45:37 INFO - Rotation: Supported 05:45:37 INFO - Return code: 0 05:45:37 INFO - Running post-action listener: _resource_record_post_action 05:45:37 INFO - ##### 05:45:37 INFO - ##### Finished run-tests step (success) 05:45:37 INFO - ##### 05:45:37 INFO - Running post-run listener: _resource_record_post_run 05:45:38 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 266240; Write bytes: 693174784; Read time: 2; Write time: 1992 05:45:38 INFO - TinderboxPrint: CPU usage
53.5% 05:45:38 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 2 05:45:38 INFO - TinderboxPrint: I/O write bytes / time
693,174,784 / 1,992 05:45:38 INFO - TinderboxPrint: CPU idle
2,255.2 (46.5%) 05:45:38 INFO - TinderboxPrint: CPU system
967.3 (19.9%) 05:45:38 INFO - TinderboxPrint: CPU user
1,627.3 (33.6%) 05:45:38 INFO - TinderboxPrint: Swap in / out
394,301,440 / 0 05:45:38 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395504128; Write bytes: 397735424; Read time: 29516; Write time: 755 05:45:38 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 266240; Write bytes: 294591488; Read time: 2; Write time: 1187 05:45:38 INFO - Running post-run listener: _upload_blobber_files 05:45:38 INFO - Blob upload gear active. 05:45:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:45:38 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 05:45:38 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:45:38 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:45:38 INFO - (blobuploader) - INFO - Open directory for files ... 05:45:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:45:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:45:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:45:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:45:39 INFO - (blobuploader) - INFO - Done attempting. 05:45:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:45:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:45:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:45:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:45:40 INFO - (blobuploader) - INFO - Done attempting. 05:45:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:45:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:45:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:45:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:45:42 INFO - (blobuploader) - INFO - Done attempting. 05:45:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:45:42 INFO - Return code: 0 05:45:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:45:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:45:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e58f5f7aba9370e6f30d6d3e232bcc3e8cef2b49ce5ab063087288fdcef23409df4544d91cf044a2ed32072f11b47537d6ba8039e5c06fc1c87172dbd5992b65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90c82f03ebd2417857aa2d985e3fc047b7c0853bc6cb2f15886c1b3c541f970ff44cec1a8fe2d84874ac42318a2695e88e79043382bcc89f55e5f140d7ad043f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4654fde72ee9f0d090f7e67c666a18fef406c76aadc2a7666de587679ebfee730da7b849ec1cfc3aeed4334acd2b26f74ce10e3ab6fa3539595493c2435b42ce"} 05:45:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:45:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:45:42 INFO - Contents: 05:45:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e58f5f7aba9370e6f30d6d3e232bcc3e8cef2b49ce5ab063087288fdcef23409df4544d91cf044a2ed32072f11b47537d6ba8039e5c06fc1c87172dbd5992b65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90c82f03ebd2417857aa2d985e3fc047b7c0853bc6cb2f15886c1b3c541f970ff44cec1a8fe2d84874ac42318a2695e88e79043382bcc89f55e5f140d7ad043f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4654fde72ee9f0d090f7e67c666a18fef406c76aadc2a7666de587679ebfee730da7b849ec1cfc3aeed4334acd2b26f74ce10e3ab6fa3539595493c2435b42ce"} 05:45:42 INFO - Running post-run listener: copy_logs_to_upload_dir 05:45:42 INFO - Copying logs to upload dir... 05:45:42 INFO - mkdir: /builds/slave/test/build/upload/logs 05:45:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.186685 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-13 05:45:42.797226) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 05:45:42.801174) ========= 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.rJ59TefqHT/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.uJDubBCaWc/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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/Firefox/sha512/e58f5f7aba9370e6f30d6d3e232bcc3e8cef2b49ce5ab063087288fdcef23409df4544d91cf044a2ed32072f11b47537d6ba8039e5c06fc1c87172dbd5992b65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90c82f03ebd2417857aa2d985e3fc047b7c0853bc6cb2f15886c1b3c541f970ff44cec1a8fe2d84874ac42318a2695e88e79043382bcc89f55e5f140d7ad043f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4654fde72ee9f0d090f7e67c666a18fef406c76aadc2a7666de587679ebfee730da7b849ec1cfc3aeed4334acd2b26f74ce10e3ab6fa3539595493c2435b42ce"} build_url:https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011260 build_url: 'https://queue.taskcluster.net/v1/task/WbLb85BoTXCvo6eUqHoI1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e58f5f7aba9370e6f30d6d3e232bcc3e8cef2b49ce5ab063087288fdcef23409df4544d91cf044a2ed32072f11b47537d6ba8039e5c06fc1c87172dbd5992b65", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90c82f03ebd2417857aa2d985e3fc047b7c0853bc6cb2f15886c1b3c541f970ff44cec1a8fe2d84874ac42318a2695e88e79043382bcc89f55e5f140d7ad043f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4654fde72ee9f0d090f7e67c666a18fef406c76aadc2a7666de587679ebfee730da7b849ec1cfc3aeed4334acd2b26f74ce10e3ab6fa3539595493c2435b42ce"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 05:45:42.831545) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:45:42.832118) ========= 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.rJ59TefqHT/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.uJDubBCaWc/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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.005223 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 05:45:42.882493) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 05:45:42.882828) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 05:45:42.883171) ========= ========= Total master_lag: 0.16 =========